builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0371 starttime: 1495758275.75 results: success (0) buildid: 20170525155044 builduid: b03060bd613c4e08ae7de68fabecb80c revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.748766) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.749240) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.749537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.774974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.775365) ========= 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.IkdALBoUQm/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.7g7JNzBVz1/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-25 17:24:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2017-05-25 17:24:35 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127097 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.924309) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.924631) ========= 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.IkdALBoUQm/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.7g7JNzBVz1/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.027723 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:35.973603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-25 17:24:35.973978) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 51c849c595a0b5428d1d56e4ae668653437283e2 --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 51c849c595a0b5428d1d56e4ae668653437283e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IkdALBoUQm/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.7g7JNzBVz1/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-25 17:24:36,088 truncating revision to first 12 chars 2017-05-25 17:24:36,088 Setting DEBUG logging. 2017-05-25 17:24:36,088 attempt 1/10 2017-05-25 17:24:36,088 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/51c849c595a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 17:24:38,363 unpacking tar archive at: mozilla-beta-51c849c595a0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.573648 ========= master_lag: 0.62 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-25 17:24:39.162997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:39.163415) ========= script_repo_revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:39.163840) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:39.164133) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:24:39.184054) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-25 17:24:39.184522) ========= /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.IkdALBoUQm/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.7g7JNzBVz1/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:24:39 INFO - MultiFileLogger online at 20170525 17:24:39 in /builds/slave/test 17:24:39 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 17:24:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:39 INFO - {'append_to_log': False, 17:24:39 INFO - 'base_work_dir': '/builds/slave/test', 17:24:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:24:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:39 INFO - 'branch': 'Mozilla-Beta', 17:24:39 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:39 INFO - 'config_files': ('talos/mac_config.py',), 17:24:39 INFO - 'default_actions': ('clobber', 17:24:39 INFO - 'read-buildbot-config', 17:24:39 INFO - 'download-and-extract', 17:24:39 INFO - 'populate-webroot', 17:24:39 INFO - 'create-virtualenv', 17:24:39 INFO - 'install', 17:24:39 INFO - 'run-tests'), 17:24:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:39 INFO - 'download_minidump_stackwalk': True, 17:24:39 INFO - 'download_symbols': 'ondemand', 17:24:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:39 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:39 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:39 INFO - 'gecko_profile': False, 17:24:39 INFO - 'gecko_profile_interval': 0, 17:24:39 INFO - 'installer_path': 'installer.exe', 17:24:39 INFO - 'log_level': 'info', 17:24:39 INFO - 'log_name': 'talos', 17:24:39 INFO - 'log_to_console': True, 17:24:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:24:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:24:39 INFO - 'opt_config_files': (), 17:24:39 INFO - 'pip_index': False, 17:24:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:39 INFO - 'cmd': ('bash', 17:24:39 INFO - '-c', 17:24:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:39 INFO - 'enabled': True, 17:24:39 INFO - 'halt_on_failure': False, 17:24:39 INFO - 'name': 'check_screen_resolution'},), 17:24:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:39 INFO - 'cmd': ('bash', 17:24:39 INFO - '-c', 17:24:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:39 INFO - 'enabled': True, 17:24:39 INFO - 'halt_on_failure': False, 17:24:39 INFO - 'name': 'check_screen_resolution'},), 17:24:39 INFO - 'run_cmd_checks_enabled': True, 17:24:39 INFO - 'suite': 'dromaeojs-e10s', 17:24:39 INFO - 'system_bits': '32', 17:24:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:39 INFO - 'title': 't-yosemite-r7-0371', 17:24:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:39 INFO - 'use_talos_json': True, 17:24:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:39 INFO - 'work_dir': 'build'} 17:24:39 INFO - [mozharness: 2017-05-26 00:24:39.402818Z] Running clobber step. 17:24:39 INFO - Running pre-action listener: _resource_record_pre_action 17:24:39 INFO - Running main action method: clobber 17:24:39 INFO - rmtree: /builds/slave/test/build 17:24:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:41 INFO - Running post-action listener: _resource_record_post_action 17:24:41 INFO - [mozharness: 2017-05-26 00:24:41.799764Z] Finished clobber step (success) 17:24:41 INFO - [mozharness: 2017-05-26 00:24:41.800040Z] Running read-buildbot-config step. 17:24:41 INFO - Running pre-action listener: _resource_record_pre_action 17:24:41 INFO - Running main action method: read_buildbot_config 17:24:41 INFO - Using buildbot properties: 17:24:41 INFO - { 17:24:41 INFO - "project": "", 17:24:41 INFO - "product": "firefox", 17:24:41 INFO - "script_repo_revision": "production", 17:24:41 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 17:24:41 INFO - "repository": "", 17:24:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 17:24:41 INFO - "buildid": "20170525155044", 17:24:41 INFO - "pgo_build": "False", 17:24:41 INFO - "basedir": "/builds/slave/test", 17:24:41 INFO - "buildnumber": 4, 17:24:41 INFO - "slavename": "t-yosemite-r7-0371", 17:24:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:24:41 INFO - "platform": "yosemite_r7_devedition", 17:24:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 17:24:41 INFO - "branch": "mozilla-beta", 17:24:41 INFO - "repo_path": "releases/mozilla-beta", 17:24:41 INFO - "revision": "51c849c595a0b5428d1d56e4ae668653437283e2", 17:24:41 INFO - "stage_platform": "macosx64-devedition", 17:24:41 INFO - "builduid": "b03060bd613c4e08ae7de68fabecb80c", 17:24:41 INFO - "slavebuilddir": "test" 17:24:41 INFO - } 17:24:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:24:41 INFO - Running post-action listener: _resource_record_post_action 17:24:41 INFO - [mozharness: 2017-05-26 00:24:41.805870Z] Finished read-buildbot-config step (success) 17:24:41 INFO - [mozharness: 2017-05-26 00:24:41.806086Z] Running download-and-extract step. 17:24:41 INFO - Running pre-action listener: _resource_record_pre_action 17:24:41 INFO - Running main action method: download_and_extract 17:24:41 INFO - mkdir: /builds/slave/test/build/tests 17:24: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')]} 17:24:41 INFO - trying https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:24:41 INFO - Downloading https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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 17:24:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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 17:24:42 INFO - Downloaded 1287 bytes. 17:24:42 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:24:42 INFO - Using the following test package requirements: 17:24:42 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:24:42 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:24:42 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:24:42 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:24:42 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:24:42 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:24:42 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:24:42 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:24:42 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:24:42 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:24:42 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:24:42 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:24:42 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:24:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:24:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:24:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:24:44 INFO - Expected file size: 51964031 17:24:44 INFO - Obtained file size: 51964031 17:25:00 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 17:25:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:25:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:25:00 INFO - Fetch https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:25:02 INFO - Expected file size: 51964031 17:25:02 INFO - Obtained file size: 51964031 17:25:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:25:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:25:17 INFO - Fetch https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:25:18 INFO - Expected file size: 14404449 17:25:18 INFO - Obtained file size: 14404449 17:25: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')]} 17:25:19 INFO - trying https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:25:19 INFO - Downloading https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:25:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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 17:25:21 INFO - Downloaded 74891863 bytes. 17:25:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:25:21 INFO - mkdir: /builds/slave/test/properties 17:25:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:25:21 INFO - Writing to file /builds/slave/test/properties/build_url 17:25:21 INFO - Contents: 17:25:21 INFO - build_url:https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:25:21 INFO - Running post-action listener: _resource_record_post_action 17:25:21 INFO - Running post-action listener: set_extra_try_arguments 17:25:21 INFO - [mozharness: 2017-05-26 00:25:21.916633Z] Finished download-and-extract step (success) 17:25:21 INFO - [mozharness: 2017-05-26 00:25:21.916757Z] Running populate-webroot step. 17:25:21 INFO - Running pre-action listener: _resource_record_pre_action 17:25:21 INFO - Running main action method: populate_webroot 17:25:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:25:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:25:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:25:21 INFO - '--tppagecycles', 17:25:21 INFO - '7'], 17:25:21 INFO - 'tests': ['tsvgm']}}, 17:25:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:25:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:25:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:25:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:25:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['damp', 'tps']}, 17:25:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'tests': ['damp', 'tps']}, 17:25:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['dromaeo_dom']}, 17:25:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:25:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['basic_compositor_video']}, 17:25:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:25:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['a11yr', 17:25:21 INFO - 'ts_paint', 17:25:21 INFO - 'tpaint', 17:25:21 INFO - 'sessionrestore', 17:25:21 INFO - 'sessionrestore_no_auto_restore', 17:25:21 INFO - 'tabpaint']}, 17:25:21 INFO - 'other-e10s': {'tests': ['a11yr', 17:25:21 INFO - 'ts_paint', 17:25:21 INFO - 'tpaint', 17:25:21 INFO - 'sessionrestore', 17:25:21 INFO - 'sessionrestore_no_auto_restore', 17:25:21 INFO - 'tabpaint']}, 17:25:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:25:21 INFO - 'ts_paint', 17:25:21 INFO - 'tpaint', 17:25:21 INFO - 'sessionrestore', 17:25:21 INFO - 'sessionrestore_no_auto_restore', 17:25:21 INFO - 'tabpaint']}, 17:25:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:25:21 INFO - 'ts_paint', 17:25:21 INFO - 'tpaint', 17:25:21 INFO - 'sessionrestore', 17:25:21 INFO - 'sessionrestore_no_auto_restore', 17:25:21 INFO - 'tabpaint']}, 17:25:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['a11yr', 17:25:21 INFO - 'ts_paint', 17:25:21 INFO - 'tpaint', 17:25:21 INFO - 'sessionrestore', 17:25:21 INFO - 'sessionrestore_no_auto_restore', 17:25:21 INFO - 'tabpaint']}, 17:25:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['a11yr', 17:25:21 INFO - 'ts_paint', 17:25:21 INFO - 'tpaint', 17:25:21 INFO - 'sessionrestore', 17:25:21 INFO - 'sessionrestore_no_auto_restore', 17:25:21 INFO - 'tabpaint']}, 17:25:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['tsvgx', 17:25:21 INFO - 'tsvgr_opacity', 17:25:21 INFO - 'tart', 17:25:21 INFO - 'tscrollx', 17:25:21 INFO - 'cart', 17:25:21 INFO - 'tsvg_static']}, 17:25:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:25:21 INFO - 'tsvgr_opacity', 17:25:21 INFO - 'tart', 17:25:21 INFO - 'tscrollx', 17:25:21 INFO - 'cart', 17:25:21 INFO - 'tsvg_static']}, 17:25:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'talos_options': ['--disable-e10s'], 17:25:21 INFO - 'tests': ['tp5o']}, 17:25:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:25:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'talos_options': ['--disable-e10s', 17:25:21 INFO - '--xperf_path', 17:25:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:21 INFO - 'tests': ['tp5n']}, 17:25:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:25:21 INFO - 'talos_options': ['--xperf_path', 17:25:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:21 INFO - 'tests': ['tp5n']}}, 17:25:21 INFO - 'talos.zip': {'path': '', 17:25:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:25:21 INFO - Running post-action listener: _resource_record_post_action 17:25:21 INFO - [mozharness: 2017-05-26 00:25:21.935110Z] Finished populate-webroot step (success) 17:25:21 INFO - [mozharness: 2017-05-26 00:25:21.935245Z] Running create-virtualenv step. 17:25:21 INFO - Running pre-action listener: _resource_record_pre_action 17:25:21 INFO - Running main action method: create_virtualenv 17:25:21 INFO - Creating virtualenv /builds/slave/test/build/venv 17:25:21 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 17:25:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:25:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:25:22 INFO - Using real prefix '/tools/python27' 17:25:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:25:22 INFO - Installing distribute.............................................................................................................................................................................................done. 17:25:25 INFO - Installing pip.................done. 17:25:25 INFO - Return code: 0 17:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:25:25 INFO - Reading from file tmpfile_stdout 17:25:25 INFO - Output received: 17:25:25 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) 17:25:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:25 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:25 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 17:25:25 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 17:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:25 INFO - 'HOME': '/Users/cltbld', 17:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:25 INFO - 'LOGNAME': 'cltbld', 17:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:25 INFO - 'MOZ_NO_REMOTE': '1', 17:25:25 INFO - 'NO_EM_RESTART': '1', 17:25:25 INFO - 'PAGER': '/bin/cat', 17:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:25 INFO - 'PWD': '/builds/slave/test', 17:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:25 INFO - 'SHELL': '/bin/bash', 17:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:25 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:25 INFO - 'USER': 'cltbld', 17:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:25 INFO - 'XPC_FLAGS': '0x0', 17:25:25 INFO - 'XPC_SERVICE_NAME': '0', 17:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:26 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 17:25:26 INFO - Cleaning up... 17:25:26 INFO - Return code: 0 17:25:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:25:26 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')]} 17:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:26 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')]} 17:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:26 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:26 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 17:25:26 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 17:25:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:26 INFO - 'HOME': '/Users/cltbld', 17:25:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:26 INFO - 'LOGNAME': 'cltbld', 17:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:26 INFO - 'MOZ_NO_REMOTE': '1', 17:25:26 INFO - 'NO_EM_RESTART': '1', 17:25:26 INFO - 'PAGER': '/bin/cat', 17:25:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:26 INFO - 'PWD': '/builds/slave/test', 17:25:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:26 INFO - 'SHELL': '/bin/bash', 17:25:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:26 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:26 INFO - 'USER': 'cltbld', 17:25:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:26 INFO - 'XPC_FLAGS': '0x0', 17:25:26 INFO - 'XPC_SERVICE_NAME': '0', 17:25:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:26 INFO - Downloading/unpacking psutil>=3.1.1 17:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:25:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:28 INFO - Installing collected packages: psutil 17:25:28 INFO - Running setup.py install for psutil 17:25:28 INFO - building 'psutil._psutil_osx' extension 17:25:28 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 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 INFO - building 'psutil._psutil_posix' extension 17:25:29 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 17:25:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:25:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:25:29 INFO - ^ 17:25:29 INFO - 1 warning generated. 17:25:29 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 17:25:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:29 INFO - Successfully installed psutil 17:25:29 INFO - Cleaning up... 17:25:29 INFO - Return code: 0 17:25:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:29 INFO - 'HOME': '/Users/cltbld', 17:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:29 INFO - 'LOGNAME': 'cltbld', 17:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:29 INFO - 'MOZ_NO_REMOTE': '1', 17:25:29 INFO - 'NO_EM_RESTART': '1', 17:25:29 INFO - 'PAGER': '/bin/cat', 17:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:29 INFO - 'PWD': '/builds/slave/test', 17:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:29 INFO - 'SHELL': '/bin/bash', 17:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:29 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:29 INFO - 'USER': 'cltbld', 17:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:29 INFO - 'XPC_FLAGS': '0x0', 17:25:29 INFO - 'XPC_SERVICE_NAME': '0', 17:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 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 17:25:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:25:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:25:31 INFO - Installing collected packages: mozsystemmonitor 17:25:31 INFO - Running setup.py install for mozsystemmonitor 17:25:31 INFO - Successfully installed mozsystemmonitor 17:25:31 INFO - Cleaning up... 17:25:31 INFO - Return code: 0 17:25:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:25:31 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')]} 17:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:31 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')]} 17:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:32 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:32 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 17:25:32 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 17:25:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:32 INFO - 'HOME': '/Users/cltbld', 17:25:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:32 INFO - 'LOGNAME': 'cltbld', 17:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:32 INFO - 'MOZ_NO_REMOTE': '1', 17:25:32 INFO - 'NO_EM_RESTART': '1', 17:25:32 INFO - 'PAGER': '/bin/cat', 17:25:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:32 INFO - 'PWD': '/builds/slave/test', 17:25:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:32 INFO - 'SHELL': '/bin/bash', 17:25:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:32 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:32 INFO - 'USER': 'cltbld', 17:25:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:32 INFO - 'XPC_FLAGS': '0x0', 17:25:32 INFO - 'XPC_SERVICE_NAME': '0', 17:25:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:32 INFO - Downloading/unpacking jsonschema==2.5.1 17:25:32 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 17:25:32 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 17:25:32 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 17:25:32 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 17:25:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:34 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 17:25:34 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 17:25:34 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 17:25:34 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 17:25:34 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:34 INFO - warning: no files found matching '*.txt' 17:25:34 INFO - no previously-included directories found matching 'build' 17:25:34 INFO - no previously-included directories found matching 'dist' 17:25:34 INFO - no previously-included directories found matching '.git*' 17:25:34 INFO - Installing collected packages: jsonschema, functools32 17:25:34 INFO - Running setup.py install for functools32 17:25:34 INFO - warning: no files found matching '*.txt' 17:25:34 INFO - no previously-included directories found matching 'build' 17:25:34 INFO - no previously-included directories found matching 'dist' 17:25:34 INFO - no previously-included directories found matching '.git*' 17:25:34 INFO - Successfully installed jsonschema functools32 17:25:34 INFO - Cleaning up... 17:25:35 INFO - Return code: 0 17:25:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25: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')]} 17:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:35 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:35 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 17:25:35 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 17:25:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:35 INFO - 'HOME': '/Users/cltbld', 17:25:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:35 INFO - 'LOGNAME': 'cltbld', 17:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:35 INFO - 'MOZ_NO_REMOTE': '1', 17:25:35 INFO - 'NO_EM_RESTART': '1', 17:25:35 INFO - 'PAGER': '/bin/cat', 17:25:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:35 INFO - 'PWD': '/builds/slave/test', 17:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:35 INFO - 'SHELL': '/bin/bash', 17:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:35 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:35 INFO - 'USER': 'cltbld', 17:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:35 INFO - 'XPC_FLAGS': '0x0', 17:25:35 INFO - 'XPC_SERVICE_NAME': '0', 17:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:25:35 INFO - Cleaning up... 17:25:35 INFO - Return code: 0 17:25:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25: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')]} 17:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:35 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:35 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 17:25:35 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 17:25:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:35 INFO - 'HOME': '/Users/cltbld', 17:25:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:35 INFO - 'LOGNAME': 'cltbld', 17:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:35 INFO - 'MOZ_NO_REMOTE': '1', 17:25:35 INFO - 'NO_EM_RESTART': '1', 17:25:35 INFO - 'PAGER': '/bin/cat', 17:25:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:35 INFO - 'PWD': '/builds/slave/test', 17:25:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:35 INFO - 'SHELL': '/bin/bash', 17:25:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:35 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:35 INFO - 'USER': 'cltbld', 17:25:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:35 INFO - 'XPC_FLAGS': '0x0', 17:25:35 INFO - 'XPC_SERVICE_NAME': '0', 17:25:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:35 INFO - Downloading/unpacking blobuploader==1.2.4 17:25:35 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 17:25:35 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 17:25:35 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 17:25:35 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 17:25:36 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:37 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 17:25:37 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 17:25:37 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 17:25:37 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 17:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:37 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 17:25:37 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 17:25:37 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 17:25:37 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 17:25:37 INFO - Downloading docopt-0.6.1.tar.gz 17:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:25:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:25:37 INFO - Running setup.py install for blobuploader 17:25:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:25:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:25:38 INFO - Running setup.py install for requests 17:25:38 INFO - Running setup.py install for docopt 17:25:38 INFO - Successfully installed blobuploader requests docopt 17:25:38 INFO - Cleaning up... 17:25:38 INFO - Return code: 0 17:25:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:38 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')]} 17:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:38 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')]} 17:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:38 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:38 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 17:25:38 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 17:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:38 INFO - 'HOME': '/Users/cltbld', 17:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:38 INFO - 'LOGNAME': 'cltbld', 17:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:38 INFO - 'MOZ_NO_REMOTE': '1', 17:25:38 INFO - 'NO_EM_RESTART': '1', 17:25:38 INFO - 'PAGER': '/bin/cat', 17:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:38 INFO - 'PWD': '/builds/slave/test', 17:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:38 INFO - 'SHELL': '/bin/bash', 17:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:38 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:38 INFO - 'USER': 'cltbld', 17:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:38 INFO - 'XPC_FLAGS': '0x0', 17:25:38 INFO - 'XPC_SERVICE_NAME': '0', 17:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-25_H6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-FtnWf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-aVhE3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-xqwEFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-hnT5Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-36DDWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-ldbk1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-zmz7HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-UkcOip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-7eSOky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-l9T5eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-rJCRiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-tC_HXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-k3dPO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-UFeaE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Jr2wyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:40 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-pCjFNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:25:40 INFO - Running setup.py install for manifestparser 17:25:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:25:40 INFO - Running setup.py install for mozcrash 17:25:41 INFO - Running setup.py install for mozdebug 17:25:41 INFO - Running setup.py install for mozdevice 17:25:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:25:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:25:41 INFO - Running setup.py install for mozfile 17:25:41 INFO - Running setup.py install for mozhttpd 17:25:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:25:41 INFO - Running setup.py install for mozinfo 17:25:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:25:41 INFO - Running setup.py install for mozInstall 17:25:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Running setup.py install for mozleak 17:25:42 INFO - Running setup.py install for mozlog 17:25:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Running setup.py install for moznetwork 17:25:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Running setup.py install for mozprocess 17:25:42 INFO - Running setup.py install for mozprofile 17:25:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Running setup.py install for mozrunner 17:25:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:25:42 INFO - Running setup.py install for mozscreenshot 17:25:43 INFO - Running setup.py install for moztest 17:25:43 INFO - Running setup.py install for mozversion 17:25:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:25:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:25:43 INFO - Cleaning up... 17:25:43 INFO - Return code: 0 17:25:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:43 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')]} 17:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:43 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')]} 17:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:43 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:43 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 17:25:43 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 17:25:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:43 INFO - 'HOME': '/Users/cltbld', 17:25:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:43 INFO - 'LOGNAME': 'cltbld', 17:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:43 INFO - 'MOZ_NO_REMOTE': '1', 17:25:43 INFO - 'NO_EM_RESTART': '1', 17:25:43 INFO - 'PAGER': '/bin/cat', 17:25:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:43 INFO - 'PWD': '/builds/slave/test', 17:25:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:43 INFO - 'SHELL': '/bin/bash', 17:25:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:43 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:43 INFO - 'USER': 'cltbld', 17:25:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:43 INFO - 'XPC_FLAGS': '0x0', 17:25:43 INFO - 'XPC_SERVICE_NAME': '0', 17:25:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:43 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-rDjp4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:43 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)) 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:43 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-I0Sa8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:43 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)) 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:43 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-q03XYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:43 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)) 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-tydBIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-_K5jyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-zLPZWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-kAj2Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-GPZi7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-JXmf2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Y9MAYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-tKMHR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-vYpv1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:44 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)) 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:44 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-VN9ION-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:45 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)) 17:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:45 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-KoVPEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:45 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)) 17:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:45 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-plhzGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:45 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)) 17:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:45 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-hxBDQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:45 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)) 17:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:45 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-AHJmMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:45 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)) 17:25:45 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)) 17:25:45 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)) 17:25:45 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)) 17:25:45 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)) 17:25:45 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)) 17:25:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25: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 17:25: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 17:25: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 17:25: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 17:25:46 INFO - Downloading blessings-1.6.tar.gz 17:25:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:25:47 INFO - Installing collected packages: blessings 17:25:47 INFO - Running setup.py install for blessings 17:25:47 INFO - Successfully installed blessings 17:25:47 INFO - Cleaning up... 17:25:47 INFO - Return code: 0 17:25:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:25:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:47 INFO - Reading from file tmpfile_stdout 17:25:47 INFO - Current package versions: 17:25:47 INFO - blessings == 1.6 17:25:47 INFO - blobuploader == 1.2.4 17:25:47 INFO - docopt == 0.6.1 17:25:47 INFO - functools32 == 3.2.3-2 17:25:47 INFO - jsonschema == 2.5.1 17:25:47 INFO - manifestparser == 1.1 17:25:47 INFO - mozInstall == 1.12 17:25:47 INFO - mozcrash == 1.0 17:25:47 INFO - mozdebug == 0.1 17:25:47 INFO - mozdevice == 0.49 17:25:47 INFO - mozfile == 1.2 17:25:47 INFO - mozhttpd == 0.7 17:25:47 INFO - mozinfo == 0.9 17:25:47 INFO - mozleak == 0.1 17:25:47 INFO - mozlog == 3.4 17:25:47 INFO - moznetwork == 0.27 17:25:47 INFO - mozprocess == 0.25 17:25:47 INFO - mozprofile == 0.28 17:25:47 INFO - mozrunner == 6.13 17:25:47 INFO - mozscreenshot == 0.1 17:25:47 INFO - mozsystemmonitor == 0.3 17:25:47 INFO - moztest == 0.8 17:25:47 INFO - mozversion == 1.4 17:25:47 INFO - psutil == 3.1.1 17:25:47 INFO - requests == 1.2.3 17:25:47 INFO - wsgiref == 0.1.2 17:25:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25: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')]} 17:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:47 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 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25:47 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 17:25:47 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 17:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:47 INFO - 'HOME': '/Users/cltbld', 17:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:47 INFO - 'LOGNAME': 'cltbld', 17:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:47 INFO - 'MOZ_NO_REMOTE': '1', 17:25:47 INFO - 'NO_EM_RESTART': '1', 17:25:47 INFO - 'PAGER': '/bin/cat', 17:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:47 INFO - 'PWD': '/builds/slave/test', 17:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:47 INFO - 'SHELL': '/bin/bash', 17:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:47 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:47 INFO - 'USER': 'cltbld', 17:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:47 INFO - 'XPC_FLAGS': '0x0', 17:25:47 INFO - 'XPC_SERVICE_NAME': '0', 17:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 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)) 17:25:47 INFO - Cleaning up... 17:25:47 INFO - Return code: 0 17:25:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25: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')]} 17:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdf5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27b4983b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, '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.IkdALBoUQm/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.7g7JNzBVz1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 17:25: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'] in /builds/slave/test/build 17:25: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 17:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:47 INFO - 'HOME': '/Users/cltbld', 17:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:47 INFO - 'LOGNAME': 'cltbld', 17:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:47 INFO - 'MOZ_NO_REMOTE': '1', 17:25:47 INFO - 'NO_EM_RESTART': '1', 17:25:47 INFO - 'PAGER': '/bin/cat', 17:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:47 INFO - 'PWD': '/builds/slave/test', 17:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:47 INFO - 'SHELL': '/bin/bash', 17:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:25:47 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:47 INFO - 'USER': 'cltbld', 17:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:47 INFO - 'XPC_FLAGS': '0x0', 17:25:47 INFO - 'XPC_SERVICE_NAME': '0', 17:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:25:48 INFO - Cleaning up... 17:25:48 INFO - Return code: 0 17:25:48 INFO - Running post-action listener: _resource_record_post_action 17:25:48 INFO - Running post-action listener: _start_resource_monitoring 17:25:48 INFO - Starting resource monitoring. 17:25:48 INFO - [mozharness: 2017-05-26 00:25:48.149979Z] Finished create-virtualenv step (success) 17:25:48 INFO - [mozharness: 2017-05-26 00:25:48.150969Z] Running install step. 17:25:48 INFO - Running pre-action listener: _resource_record_pre_action 17:25:48 INFO - Running main action method: install 17:25:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:48 INFO - Reading from file tmpfile_stdout 17:25:48 INFO - Detecting whether we're running mozinstall >=1.0... 17:25:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:25:48 INFO - Reading from file tmpfile_stdout 17:25:48 INFO - Output received: 17:25:48 INFO - Usage: mozinstall [options] installer 17:25:48 INFO - Options: 17:25:48 INFO - -h, --help show this help message and exit 17:25:48 INFO - -d DEST, --destination=DEST 17:25:48 INFO - Directory to install application into. [default: 17:25:48 INFO - "/builds/slave/test"] 17:25:48 INFO - --app=APP Application being installed. [default: firefox] 17:25:48 INFO - mkdir: /builds/slave/test/build/application 17:25:48 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'] 17:25:48 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 17:26:08 INFO - Reading from file tmpfile_stdout 17:26:08 INFO - Output received: 17:26:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:26:08 INFO - Running post-action listener: _resource_record_post_action 17:26:08 INFO - [mozharness: 2017-05-26 00:26:08.629466Z] Finished install step (success) 17:26:08 INFO - [mozharness: 2017-05-26 00:26:08.629573Z] Running run-tests step. 17:26:08 INFO - Running pre-action listener: _resource_record_pre_action 17:26:08 INFO - Running main action method: run_tests 17:26:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:26:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:26:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:26:08 INFO - 2017-05-25 17:26:08.642 screenresolution[1624:7269] starting screenresolution argv=screenresolution get 17:26:08 INFO - 2017-05-25 17:26:08.644 screenresolution[1624:7269] Display 0: 1600x1200x32@60 17:26:08 INFO - 2017-05-25 17:26:08.649 screenresolution[1625:7272] starting screenresolution argv=screenresolution list 17:26:08 INFO - Available Modes on Display 0 17:26:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:26:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:26:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:26:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:26:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:26:08 INFO - Intel Iris: 17:26:08 INFO - Chipset Model: Intel Iris 17:26:08 INFO - Type: GPU 17:26:08 INFO - Bus: Built-In 17:26:08 INFO - VRAM (Dynamic, Max): 1536 MB 17:26:08 INFO - Vendor: Intel (0x8086) 17:26:08 INFO - Device ID: 0x0a2e 17:26:08 INFO - Revision ID: 0x0009 17:26:08 INFO - Displays: 17:26:08 INFO - Display: 17:26:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:26:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:26:08 INFO - Display Serial Number: 1600x1200 60 17:26:08 INFO - Main Display: Yes 17:26:08 INFO - Mirror: Off 17:26:08 INFO - Online: Yes 17:26:08 INFO - Rotation: Supported 17:26:08 INFO - Return code: 0 17:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:08 INFO - Python 2.7.3 17:26:08 INFO - Return code: 0 17:26:08 INFO - grabbing minidump binary from tooltool 17:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:08 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 0x10bbd0d30>, '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 0x10baf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:08 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 17:26:08 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 17:26:08 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 17:26:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:09 INFO - Return code: 0 17:26:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:26:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:09 INFO - ENV: RUST_BACKTRACE is now 1 17:26:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:26:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:09 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--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 17:26:09 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0371 --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 17:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IkdALBoUQm/Render', 17:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:09 INFO - 'HOME': '/Users/cltbld', 17:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:09 INFO - 'LOGNAME': 'cltbld', 17:26:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:09 INFO - 'MOZ_NO_REMOTE': '1', 17:26:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:09 INFO - 'NO_EM_RESTART': '1', 17:26:09 INFO - 'PAGER': '/bin/cat', 17:26:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:09 INFO - 'PWD': '/builds/slave/test', 17:26:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:09 INFO - 'RUST_BACKTRACE': '1', 17:26:09 INFO - 'SHELL': '/bin/bash', 17:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7g7JNzBVz1/Listeners', 17:26:09 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 17:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:09 INFO - 'USER': 'cltbld', 17:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:09 INFO - 'XPC_FLAGS': '0x0', 17:26:09 INFO - 'XPC_SERVICE_NAME': '0', 17:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:09 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--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 17:26:09 INFO - mozversion application_buildid: 20170525155044 17:26:09 INFO - mozversion application_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:26:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:26:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:09 INFO - mozversion application_name: Firefox 17:26:09 INFO - mozversion application_remotingname: firefox-dev 17:26:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:26:09 INFO - mozversion application_vendor: Mozilla 17:26:09 INFO - mozversion application_version: 54.0 17:26:09 INFO - mozversion platform_buildid: 20170525155044 17:26:09 INFO - mozversion platform_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:26:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:26:09 INFO - mozversion platform_version: 54.0 17:26:09 INFO - using testdate: 1495758369 17:26:09 INFO - actual date: 1495758369 17:26:09 INFO - starting webserver on 'localhost:49227' 17:26:09 INFO - SUITE-START | Running 2 tests 17:26:09 INFO - TEST-START | dromaeo_css 17:26:09 INFO - operating with platform_type : mac_ 17:26:09 INFO - Initialising browser for dromaeo_css test... 17:26:09 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpPULo7I/profile http://localhost:49227/getInfo.html) 17:26:12 INFO - PROCESS | 1635 | 2017-05-25 17:26:12.378 plugin-container[1638:7464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 17:26:12 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:12 INFO - PROCESS | 1635 | 2017-05-25 17:26:12.379 plugin-container[1638:7464] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 17:26:12 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:12 INFO - PROCESS | 1635 | 2017-05-25 17:26:12.379 plugin-container[1638:7464] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:12 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 17:26:12 INFO - PROCESS | 1635 | colorDepth:24 17:26:12 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 17:26:12 INFO - PROCESS | 1635 | __metrics 17:26:12 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 17:26:13 INFO - TEST-INFO | 1635: exit 0 17:26:13 INFO - Browser initialized. 17:26:13 INFO - Running cycle 1/1 for dromaeo_css test... 17:26:13 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpPULo7I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:15 INFO - PROCESS | 1639 | 2017-05-25 17:26:15.512 plugin-container[1640:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 17:26:15 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:15 INFO - PROCESS | 1639 | 2017-05-25 17:26:15.513 plugin-container[1640:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 17:26:15 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:15 INFO - PROCESS | 1639 | 2017-05-25 17:26:15.513 plugin-container[1640:7599] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:25 INFO - PROCESS | 1639 | RSS: Main: 203358208 17:26:25 INFO - PROCESS | 1639 | 17:29:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 17:29:30 INFO - PROCESS | 1639 | RSS: Main: 218103808 17:29:30 INFO - PROCESS | 1639 | 17:32:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 17:32:33 INFO - PROCESS | 1639 | RSS: Main: 231772160 17:32:33 INFO - PROCESS | 1639 | 17:35:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 17:35:35 INFO - PROCESS | 1639 | RSS: Main: 233127936 17:35:35 INFO - PROCESS | 1639 | 17:38:37 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 17:38:38 INFO - PROCESS | 1639 | RSS: Main: 236449792 17:38:38 INFO - PROCESS | 1639 | 17:41:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 17:41:41 INFO - PROCESS | 1639 | RSS: Main: 238477312 17:41:41 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 17:44:44 INFO - PROCESS | 1639 | RSS: Main: 236691456 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | __start_tp_report 17:44:44 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 17:44:44 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 17:44:44 INFO - PROCESS | 1639 | |i|pagename|runs| 17:44:44 INFO - PROCESS | 1639 | |0;dojo.html;3017.5926249956256;3319.369319829234;3519.961611324659;3520.80635565044;3533.3286675531663;5948.1375200595885;5976.147394307768;6002.249718785158;6006.849828754283;6018.15745795589;5137.666051706637;5843.64938103714;6002.099685047245;6013.669248191348;6093.969530152354;5359.705216213107;5372.623916325859;5447.754851031713;5475.206095116206;5562.16567514874;5498.835034948958;5510.889782204354;5521.1442226454965;5523.475269849358;5530.253415788858;5304.416514183436;5308.734563271841;5396.676199428047;5422.267993820843;5530.170474428848;4324.545922678137;4752.7623618818925;5434.049041417736;5446.101393270105;5546.140348246028;4565.954340456619;5371.946280537184;5397.27136836526;5458.781648734046;5508.889822203533;5850.151727999416;6076.270847498285;6082;6112.327643959161;6134.141220229171;5290.4974027467715;5302.469753024705;6284.214473190851;6329.8417539561415;6349.936500635027;5187.48125187482;6432.260290066655;6460.224773027267;6461.870762584722;6466.902996455057;6053.27360716708;6139.201903752483;6163.7842675506145;6185.195924529828;6202.565820392574;5381.48875856793;6236.501079913596;6337.683115844189;6400.455961243253;6429.485641148697;6257.124002639634;6290.99344104941;6299.149614802035;6319.304876463585;6320.71556779946;6364.64994425311;6400.103985442041;6421.068945002949;6424.775132870421;6439.356064393523;6215.098810672517;6261.624302541862;6296.937030629727;6353.142325786052;6396.456301214449;6253.187085678924;6274.466670332981;6292.1505596743855;6306.747730090745;6348.238211414659;4707.81168753246;4888.877778055577;4902.877428064256;4927.827526036572;4969.055879382906;4435.334699794992;4446.644268458515;4453.643708503377;4467.061916997402;4510.939929116655;5781.653100813881;5862.062070068769;5929.495992840657;6078.483328917003;6107.877842443127;6342.746290148342;6353.936460635427;6372.6495042773095;6430.421262086435;6447.903281450782;6041.24484439445;6058.939410605837;6059;6061.060535616986;6079.726412311456;5409.83770486886;5646.943530564724;5655.462731040545;5657.490825825613;5692.9146062809095;5610.915836262541;6418.582792118591;6428.389303016206;6463.644499552382;6544.709351726858;6467.159269294962;6475.967620161869;6499.285078641251;6500.577462464925;6512.120863683532;6491.837704057436;6647.634380109141;6655.800325990228;6667.966660166862;6668.266490685924;6235.625862448268;6267.2165979252595;6281.371862813682;6308.08532762756;6387.329330420239;6450.41946224842;6466;6473.708683109366;6492.253390859923;6493.707783149675;4942.827001054946;6260.436560709558;6305.716242768995;6319.652419116993;6321.841953951188;5102.540771330598;6197.411245931629;6209.2548894133;6246.219222597176;6291.433770960635;4790;6153.5692501526255;6188.566800324114;6214.130021797043;6294.339094395023;6238.845813524513;6300.243970723543;6325.304216536086;6328.651924144032;6344.429001389897;6133.640713961282;6185.195924529782;6217.160683307877;6255;6260.280067792262 17:44:44 INFO - PROCESS | 1639 | |1;ext.html;737.5758938610298;763.6143747407558;821.7493664432345;823.773462297868;953.8187744328577;38715;39457.60542394575;41182.58817411825;41193.58806411935;41279.99999999996;39689;39857.00357491064;40005.39991900117;40159.19681606366;40319.59680403202;6904.723811047552;8342.040665323475;10987.725306867313;11256.212065155443;11437.97058264756;9761.658341958033;11051.392173430459;11105.55577776888;11226.101911847032;11233.775324493505;11212.831807522894;11246.044086252678;11251.212415130944;11267.661970140909;11273.887261127407;11173.329600223971;11196.104311655048;11235.325880447117;11241.269317494336;11265.774684506265;10980.725481862937;11024;11037.889621103846;11041.33751974876;11052.557897684082;34856.65143348585;35021.824890875636;35060.474092888624;35184.82407587971;35262.64737352619;3819.2552452271816;3857.8821379262517;3884.184321292532;3884.3008258513455;3930.1353702185475;25927.35181620456;26143.73856261451;26382.86808565964;27673.616319184162;27818;2848.886044558215;3083.260017595783;3164.019154062248;3179.4118088153764;3192.4892017277125;323483;325226;325588;326953;327211;40558.78323650295;45124.548754512034;45244.54755452469;45248.3212751809;45388.77305613451;46490.5350946493;46696;46717.532824671995;46751;46770.5322946773;13571.864281357257;13996.160230386005;14002.50991215323;14008.50970216058;14032.789508157386;11926.701832454084;11975.820362694567;11992.520299187934;11998.520059197708;12011.399430028638;11829.52681892732;11850.822237666442;11862.703432414259;11865.466054027589;11976.221545599508;5722.685252311163;5762.88474230513;5770.740316685759;5775.306963164448;5791.623544469596;1111.666500049998;1136.4601814138218;1161.7386088130104;1182.7930112230326;1195.8699029417292;27750.722492775218;27875.30311742182;28000.87996480159;28386.716132838406;29439;1061.2624226162743;1073.8241625420308;1097.9945100274447;1105.729096371378;1110.2006555280186;13862.93068534651;14245.318838552288;14251.429942802379;14430.206338651475;14499.057561258485;496254;500743;501347;501578;502220;48893.755531222116;49157;49203.753981229864;49279.50720492821;49550.256746148836;1192.8151136573845;1235.6469665716027;1404.5996890886254;1435.1819462906044;1437.475321507642;26626.604752799936;27331.31671708223;27457.31356716018;28287.57568636431;28376.43247135087;34196.31607367789;41511.58488415077;41665;41668.791656041525;41774.791126044176;430234.84882575384;431224.8438757786;433162;433317;433729;30302.090937271918;30392.696073038987;30530.626121825015;30534.931277405183;30629.693703062683;38700.6129938697;38800.835974920796;39336;39993.00017499586;39993.80003100082;27498.45003099887;27905;28007.75937925002;28206.85896570504;28930.27674308159;27736;27744.445111097262;27860.582091268243;28427;28686.56970145491;31806.36387272195;32458.052516849064;32552.348953020333;32690.346193076482;34539.30921381609;22331.888340558195;25677.033088345222;28545.714542854304;28697;29590.556141658322;18388.632227355647;19754.305144236136;22307.10771569119;24119.03523859026;24434.14480493174 17:44:44 INFO - PROCESS | 1639 | |2;jquery.html;378.2529504229147;407.5618709886871;407.6290575576226;416.86035178215303;452.3170013279946;20586.89706551467;20723.896380518094;21848;22022.669659955114;22278.220262290823;22103.889480552614;22375.888120559415;22592.8870355648;23143.30570082896;23272.767272327314;6899.689513971884;7119.5372300800545;7132.322429369202;7171.784846454615;7218.639068046603;6849.863002739942;6873.312668733111;7004.474664400191;7087.468439867005;7128.572285662877;6894.758683446081;6914.3777060064585;7040.29597040297;7044.260352662974;7087.220405755363;6728.93271067288;7000.579965202104;7026.086608740882;7088.9012203108605;7094.645267736644;6702;6732.83167920801;6761.782879081763;6769.593824370504;6783.559068660571;26397.3400664983;26502;27195.72804271952;27309.86345068262;27443.588346174824;2870.956935645967;2920.1385591250632;2935.354222071141;2946.0425361757516;2951.6900725423734;15678.76481852773;16434.83565164357;16504;16696.916515417346;16771.077590732504;2121.95756084879;2125.234915430444;2144.088762276042;2163.7836216378396;2165.902534385956;119474;134041.32979335138;134315;145161.27419362942;181556.09221953937;20427.897860510602;21568.676469852966;21762.782372176393;21811.127554897626;21846.89076554607;24879.378015549755;30499.847500762353;31005.379892402027;31159.376812463623;31270;8396.958015209884;9889.56601292823;9932.702018939442;10079.899201007896;10142.594296228068;8262.504249745034;8280.130586288473;8281.337493000545;8306.753986902013;8330.042045164762;9547.427154370622;9699.563519641633;9739.26955478342;9766.706998790047;9792.21662267017;3731.552213734369;4101.420952933142;4151.792410379529;4159.854405095807;4171.249175148502;938.7606160429046;992.6476100984191;1080.357187473452;1090.929089609173;1125.6397952655236;21369.931503425076;21622.567548648938;21659.891700541397;21715.34853954384;21747.347579572637;1075.908547773448;1087.6302057300556;1089.4171618184234;1115.3976852499559;1139.4587570903755;11173.385463799406;12127.696807579881;12360.814587781191;12527.686807829878;12600.74798504043;749936;769715;778246;784797;793424.0328798319;158880;171092;174402;175656;179158;1032.4734385463319;1273.376045737582;1278.9345207490023;1317.9802302965463;1319.4854006937303;19045.809541904957;19363.709544356563;20146.89926550358;20149.59700806005;20269.49326266855;68694.65652671705;69299.96050059152;69511.60976780538;69671.60656786937;71459.64270178616;92940;94027;95795;96913.03086969322;97541.51229243808;11354.489047993025;11663.5917742882;11695.005924496512;11763.529458821893;12056.819147712968;13186;13246.072774905664;13656.317184140555;13666.385012674653;13780.379882905494;11401.543938242377;11551.595694150665;11586.826197606873;11661.6501504955;11786.646400607995;10771.946140269249;10780.514876830404;10827.891721082688;11153.498092586015;11204.991550760471;11329;11570.132240081859;11588.710282243012;11670.474828632901;11740.00209982127;11733.296002239893;11832.408379581158;12041.879581204075;12125.029997600348;12129.21160124589;11549.42252887369;11579.13156513248;11629.651110466699;11928.403579821148;11943.522259109539 17:44:44 INFO - PROCESS | 1639 | |3;mootools.html;1284.916480428772;1563.695079459506;1566.8824838137143;1582.857542821146;1585.128179501274;10812.891871081296;11119.833202501968;11418.600348987788;11492.942535287322;11513.48189331481;11394.316341019545;11515.596954106608;11605.941970290136;11621.360825154614;11628.186026978115;2055.8972051397373;2357.1867705641525;2387.3554140382084;2399.892004859777;2405.158194631874;2301.2866011536476;2381.357033600935;2395.281415575329;2397.8081753459683;2399.7360290368047;2318.6290193569034;2318.7797159269844;2319.026009076192;2391.5695174868515;2392.629142482918;2348.941276468085;2364.5270945810908;2365.6806331145335;2380.4405964598304;2393.9880300598475;1954.2378472395776;2014.8186663200383;2058.8147066763913;2309.976900230993;2343;11326.433678316134;11341.546338146465;11405.885941140565;11412.600558980395;11427.714307142305;5582.972085139589;5695.202671625976;5740.339860916007;5749.540036797088;5767.798127065533;3152.7793054486197;3220.22714548509;3377.7635565510423;3571.5535558055244;3588.9641103588892;1139.9430028498625;1149.8673806300787;1165.7399763271287;1172.6775136837352;1181.5273890443805;12548.560800372034;12801.807972880415;14991.32539035746;15556.455524056604;15611.219439028004;4544.7045942013665;4600.930986035212;4670.813167473263;4683.227267500893;4706.3411122442885;11899.464524096436;12047.216930899463;12049.457774400173;12054.879451205376;12060.396980150957;5668.149777533403;5670.5463562914865;5692.0323544997445;5695.943040569624;5696.174054762037;4658.114958157939;5143.099957507496;5212.765425555859;5237.135872580981;5248.973755131276;3590.281943611236;3735.6637902588895;3736.308782875177;3747.2130852521277;3814.5422549294262;954.3081266082035;954.3367359684952;961.5528779117744;970.834958057132;971.8833739951251;949.6343907595488;967.9322447428613;979.2215188924822;983.188869182927;1007.8941711120374;6759.256481787;6906.896396554056;6923.307669233137;6950.930490695028;7003.159620845531;966.9129778319985;1002.1782138646386;1013.9036791504795;1016.2124353625999;1025.1849779425227;4197.349410841324;4249.2563801334645;4263.339182426729;4407.757573333433;4427.889302767392;13398.196108233537;13844.30778461073;13851.376688049062;13913.956453266046;13920.37358318878;12099;13487.055906086676;13820.96342774296;13845.584632461043;13851.099678520864;10202.897971020195;10481.580736770597;10498.47507624631;10505.52725127371;10521;6745.494087943326;7001.579905205704;7101.2898710128575;7175.784726458214;7187.209406965334;12479;12685.873141268838;12755.426005829575;12780.808287875876;12826.358682065673;11755.177137600285;12515.186512876633;12769.872301276868;12845.871541284467;13095.148815326973;9235.538223088684;9400.717978460658;9471.47906865129;9519.381240219363;9520.095590918852;10275.7431064224;10324.07083362501;10436.843447348449;10503.949605039741;10532.947335263274;8945.552722363725;9034.3675942686;9075.591598378222;9111.954440227757;9213.216876565299;8776.605052772513;8815.382923195315;8815.515146666994;8891.466512009041;8912.287017038492;6827.6244806536115;7854.882176767467;8016.358691304798;8071.878921816295;8072.071711753224;4953.851384458328;5898.616589921641;5949.405059494016;6075.878482430238;6087.421694938974;5633.436656334334;5675.602707810579;5682.971585142048;5715.114157305624;5777.364489906191 17:44:44 INFO - PROCESS | 1639 | |4;prototype.html;383.77165586476053;385.19878652403;387.9456876037355;394.6448196623037;398.3705744923022;4495.348174514694;5068.923966140511;5146.864051418656;5150;5232.529072383485;4355;4833.78247978841;5144.485551444854;5155.123628983072;5158.819441319554;3547.7339199560006;3828.4831547741114;4014.357702767558;4319.503257125431;4343.305071188611;3503.982480087596;3612.566492020961;3835.9424608630893;4319.82720691172;4361.146512732092;3848.634379733921;3848.903777405559;4332.631726303268;4368.226176377991;4412.426384570017;3705.277470893177;3969.4244334571326;4011.65900898424;4317.963688714717;4341.023269764293;3165.9050228493184;3660.2979614479395;3973.4238535412205;3978.781167035812;3980.124372638015;4125.411716489143;4467.620252278561;4503.774811259456;4731.640535219998;5025.497450254945;4380.561943805626;4391.670624703128;4967.615544538642;5027.874303142451;5082.237664350377;4682.852701088221;4715.599174070208;5670.234518340013;5692.601517893749;5737.340205876294;4129.318662420697;4453.977730111329;4457.3091170868565;4465.263231566788;4469.028096315946;5107.627762862909;5192.324997750268;5589.860253493654;5618.775248990036;5863.299643103494;4282.550332215135;4356.368326592689;4824.6863953842885;5043.36957880265;5047.949520504748;4589.196890544142;4676.625869930397;5112.948870511322;5152.407473140562;5181.476595914814;4478.328250762412;4787.42550893895;5327.227552004938;5351.3043304370185;5353.785848566014;4811.628685824593;4951.529604687549;5245.396779370345;5273.894522109613;5587.631030397579;1577.826439091699;1609.88730788846;1695.465928232603;1738.1048759888668;1743.8256174382714;1239.5971309324505;1240.8573014103495;1256.7989121740477;1426.3296250762126;1440.2006886178062;4380.6933514653665;4633.212353899845;4936.777844996984;4951.3810773653295;5008.8229266122435;4841.080194762983;4874.82938097172;5649.858753531194;5672.50409819678;5697.4302569742695;4585.70192937458;4770.499097594772;4986.202207646759;5007.599392048627;5016.648834581544;2440.025112089898;2562.256945485788;2655.6058069513274;2768.986155069252;2826.1239015904725;5023.422306434733;5238.813574561157;5507.669539827623;5674.347450043298;5901.911471327933;5229.980153870035;5390.687450039953;5481.588880833953;5847.736851841677;5860.238169037998;4983.750812459362;5663.886722265532;5903.26209223847;5905.291365036223;5928.822135335947;5721.199032135588;5731.3665605302285;5753.101476512825;5830.591858569859;5836.737346819318;5008.298838162733;5489.451054894478;5536.97231513856;5588.954020059159;5642.040853054908;4980.555638864688;5708.400617935057;5847.44449277318;5901.881962360643;5945.881082378241;4227.837344730101;4540;4545;4555.589996900295;4602;5147.459516750687;5424.755885985219;5637.43625637433;5671.716414179357;5764.596478246483;5505.752241149078;5505.889882202254;5529.723513824373;5533.418991005887;5573.575494659836;4672.462666793363;5110.437851836369;5146.114683413998;5409.539424355481;5422.457754224546;4448.421705178306;4644.142343062802;4716.98584804255;4978.228374601942;5380;4848.951510484992;5045.470225626404;5191.883744994844;5536.511997441586;5582.525485333792;5135.845924622267;5449.3188351456065;5476;5532.960938514826;5561.110222364402 17:44:44 INFO - PROCESS | 1639 | |5;yui.html;658.7035833874758;738.5827007740629;746.3059354800035;794.150259222632;885.4244740918402;15555.533333999983;16325.265363058661;16780.83219167808;16798.91600541997;17259.654806903854;15219.315130819112;15549.300281487333;15625.765613515807;15931.362745490167;15958.202089895487;962.1340793286029;991.3308516751164;994.8408254679252;995.0348162282611;995.8655581496478;955.2739917662592;977.2426369563609;987.6247026130061;991.504247876062;992;925.9907400925972;1037.2998226197324;1039.8960103989577;1040.4537617750666;1040.9115225205867;986.7631768375574;1027.609508386812;1034.6378767431338;1034.7413146713284;1035.6996471023394;764.7973287078928;772.5055964182927;774.3766268154134;774.7636970723927;774.8295375017487;14625.122492650475;16223.107729074896;17022;17882;18086.276548938025;14160.221187834664;14227.35976881043;14395.856041439556;14399.280035998263;14507.492237771627;10004.22433715176;10228.744281393025;11670.708232294175;11940.88059119415;11960.72006592995;3565.3938830398893;3698.9445158322646;3877.2051729395407;3906.5507466641416;3932.134930315326;95386;96937;99702.50148749282;102095;102121.48939255331;10658.946705266424;10667.359958402521;10883.891161088288;11130.942560456744;11506.654800356002;20901.79098209029;21496.78503214979;21504.892475537836;21559.46101347479;21633.810140442427;7816.7264145754625;7889.09275433321;7913.920860791433;7938.841223175504;8003.719869804529;4439.644828413784;4462.129884672458;4513.68404211702;4553.54464553542;4562.064776720765;3158.0841555948978;3169.5879535660215;3218.935621287561;3223.3875563642832;3226.209578653245;581.5609215042616;617.6973283091253;622.6948795090373;634.1850721822436;636.0332294911801;632.3297304856775;658.4469046001382;692.2834865913856;693.8646963842002;722.37514549915;9556.904430955741;10484.318519296221;10506.894931050745;10633.17822057828;10668;700.9684564194623;721.1850608812114;722.1767185408638;725.992740072603;728.8141523911369;4926.802927882844;5135.152699804565;5140.614453915971;5145.434002259748;5146.073706732824;84717.57641211755;86416;94788.57817132749;102194.48902755439;103145;89630;91366.54316728507;91370;91767.54116229509;92484;77629;78965;79708.60145699234;80905;81326;9835.950820245853;9853.261005424765;9906.653267135616;9951.402915825074;9974.503824426394;52674;53242.46757532375;53890;54448.36654900359;54900.72549637226;66059;67096.99354509784;67838.66080669565;68463;69558;11419.942900285445;11449.59926402572;11565.768684626091;11642.185047046625;12195.51217951272;12906;12957.611271661865;13137.671558210739;13206.066814922242;13276.26980516081;12096.637100886988;12132.090093243218;12436.502539898665;12590.24458532491;13374.799378009144;13804;14018.859811402164;14458;14813.185274809557;14963.551093467213;13405.396757145756;13619.25094119833;13858.548798928996;14069.366878490287;14272.500462483764;8873.378863479753;9234.261259099121;9375.484348360633;9424.387414818015;9960.203183745429;10126.949365253127;10357.274990750575;11460.255083419552;11799.764004719686;12045.156839021534 17:44:44 INFO - PROCESS | 1639 | __end_tp_report 17:44:44 INFO - PROCESS | 1639 | __start_cc_report 17:44:44 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,374 17:44:44 INFO - PROCESS | 1639 | __end_cc_report 17:44:44 INFO - PROCESS | 1639 | __startTimestamp1495759484666__endTimestamp 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | ------- Summary: start ------- 17:44:44 INFO - PROCESS | 1639 | Number of tests: 6 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5869.85 Median:6163.78 stddev:708.00 (11.5%) stddev-sans-first:673.89 17:44:44 INFO - PROCESS | 1639 | Values: 3017.6 3319.4 3520.0 3520.8 3533.3 5948.1 5976.1 6002.2 6006.8 6018.2 5137.7 5843.6 6002.1 6013.7 6094.0 5359.7 5372.6 5447.8 5475.2 5562.2 5498.8 5510.9 5521.1 5523.5 5530.3 5304.4 5308.7 5396.7 5422.3 5530.2 4324.5 4752.8 5434.0 5446.1 5546.1 4566.0 5371.9 5397.3 5458.8 5508.9 5850.2 6076.3 6082.0 6112.3 6134.1 5290.5 5302.5 6284.2 6329.8 6349.9 5187.5 6432.3 6460.2 6461.9 6466.9 6053.3 6139.2 6163.8 6185.2 6202.6 5381.5 6236.5 6337.7 6400.5 6429.5 6257.1 6291.0 6299.1 6319.3 6320.7 6364.6 6400.1 6421.1 6424.8 6439.4 6215.1 6261.6 6296.9 6353.1 6396.5 6253.2 6274.5 6292.2 6306.7 6348.2 4707.8 4888.9 4902.9 4927.8 4969.1 4435.3 4446.6 4453.6 4467.1 4510.9 5781.7 5862.1 5929.5 6078.5 6107.9 6342.7 6353.9 6372.6 6430.4 6447.9 6041.2 6058.9 6059.0 6061.1 6079.7 5409.8 5646.9 5655.5 5657.5 5692.9 5610.9 6418.6 6428.4 6463.6 6544.7 6467.2 6476.0 6499.3 6500.6 6512.1 6491.8 6647.6 6655.8 6668.0 6668.3 6235.6 6267.2 6281.4 6308.1 6387.3 6450.4 6466.0 6473.7 6492.3 6493.7 4942.8 6260.4 6305.7 6319.7 6321.8 5102.5 6197.4 6209.3 6246.2 6291.4 4790.0 6153.6 6188.6 6214.1 6294.3 6238.8 6300.2 6325.3 6328.7 6344.4 6133.6 6185.2 6217.2 6255.0 6260.3 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:54665.01 Median:29184.64 stddev:113165.37 (387.8%) stddev-sans-first:113410.40 17:44:44 INFO - PROCESS | 1639 | Values: 737.6 763.6 821.7 823.8 953.8 38715.0 39457.6 41182.6 41193.6 41280.0 39689.0 39857.0 40005.4 40159.2 40319.6 6904.7 8342.0 10987.7 11256.2 11438.0 9761.7 11051.4 11105.6 11226.1 11233.8 11212.8 11246.0 11251.2 11267.7 11273.9 11173.3 11196.1 11235.3 11241.3 11265.8 10980.7 11024.0 11037.9 11041.3 11052.6 34856.7 35021.8 35060.5 35184.8 35262.6 3819.3 3857.9 3884.2 3884.3 3930.1 25927.4 26143.7 26382.9 27673.6 27818.0 2848.9 3083.3 3164.0 3179.4 3192.5 323483.0 325226.0 325588.0 326953.0 327211.0 40558.8 45124.5 45244.5 45248.3 45388.8 46490.5 46696.0 46717.5 46751.0 46770.5 13571.9 13996.2 14002.5 14008.5 14032.8 11926.7 11975.8 11992.5 11998.5 12011.4 11829.5 11850.8 11862.7 11865.5 11976.2 5722.7 5762.9 5770.7 5775.3 5791.6 1111.7 1136.5 1161.7 1182.8 1195.9 27750.7 27875.3 28000.9 28386.7 29439.0 1061.3 1073.8 1098.0 1105.7 1110.2 13862.9 14245.3 14251.4 14430.2 14499.1 496254.0 500743.0 501347.0 501578.0 502220.0 48893.8 49157.0 49203.8 49279.5 49550.3 1192.8 1235.6 1404.6 1435.2 1437.5 26626.6 27331.3 27457.3 28287.6 28376.4 34196.3 41511.6 41665.0 41668.8 41774.8 430234.8 431224.8 433162.0 433317.0 433729.0 30302.1 30392.7 30530.6 30534.9 30629.7 38700.6 38800.8 39336.0 39993.0 39993.8 27498.5 27905.0 28007.8 28206.9 28930.3 27736.0 27744.4 27860.6 28427.0 28686.6 31806.4 32458.1 32552.3 32690.3 34539.3 22331.9 25677.0 28545.7 28697.0 29590.6 18388.6 19754.3 22307.1 24119.0 24434.1 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:44612.08 Median:21829.01 stddev:129439.91 (593.0%) stddev-sans-first:129760.41 17:44:44 INFO - PROCESS | 1639 | Values: 378.3 407.6 407.6 416.9 452.3 20586.9 20723.9 21848.0 22022.7 22278.2 22103.9 22375.9 22592.9 23143.3 23272.8 6899.7 7119.5 7132.3 7171.8 7218.6 6849.9 6873.3 7004.5 7087.5 7128.6 6894.8 6914.4 7040.3 7044.3 7087.2 6728.9 7000.6 7026.1 7088.9 7094.6 6702.0 6732.8 6761.8 6769.6 6783.6 26397.3 26502.0 27195.7 27309.9 27443.6 2871.0 2920.1 2935.4 2946.0 2951.7 15678.8 16434.8 16504.0 16696.9 16771.1 2122.0 2125.2 2144.1 2163.8 2165.9 119474.0 134041.3 134315.0 145161.3 181556.1 20427.9 21568.7 21762.8 21811.1 21846.9 24879.4 30499.8 31005.4 31159.4 31270.0 8397.0 9889.6 9932.7 10079.9 10142.6 8262.5 8280.1 8281.3 8306.8 8330.0 9547.4 9699.6 9739.3 9766.7 9792.2 3731.6 4101.4 4151.8 4159.9 4171.2 938.8 992.6 1080.4 1090.9 1125.6 21369.9 21622.6 21659.9 21715.3 21747.3 1075.9 1087.6 1089.4 1115.4 1139.5 11173.4 12127.7 12360.8 12527.7 12600.7 749936.0 769715.0 778246.0 784797.0 793424.0 158880.0 171092.0 174402.0 175656.0 179158.0 1032.5 1273.4 1278.9 1318.0 1319.5 19045.8 19363.7 20146.9 20149.6 20269.5 68694.7 69300.0 69511.6 69671.6 71459.6 92940.0 94027.0 95795.0 96913.0 97541.5 11354.5 11663.6 11695.0 11763.5 12056.8 13186.0 13246.1 13656.3 13666.4 13780.4 11401.5 11551.6 11586.8 11661.7 11786.6 10771.9 10780.5 10827.9 11153.5 11205.0 11329.0 11570.1 11588.7 11670.5 11740.0 11733.3 11832.4 12041.9 12125.0 12129.2 11549.4 11579.1 11629.7 11928.4 11943.5 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6782.71 Median:2396.54 stddev:4283.16 (178.7%) stddev-sans-first:4275.25 17:44:44 INFO - PROCESS | 1639 | Values: 1284.9 1563.7 1566.9 1582.9 1585.1 10812.9 11119.8 11418.6 11492.9 11513.5 11394.3 11515.6 11605.9 11621.4 11628.2 2055.9 2357.2 2387.4 2399.9 2405.2 2301.3 2381.4 2395.3 2397.8 2399.7 2318.6 2318.8 2319.0 2391.6 2392.6 2348.9 2364.5 2365.7 2380.4 2394.0 1954.2 2014.8 2058.8 2310.0 2343.0 11326.4 11341.5 11405.9 11412.6 11427.7 5583.0 5695.2 5740.3 5749.5 5767.8 3152.8 3220.2 3377.8 3571.6 3589.0 1139.9 1149.9 1165.7 1172.7 1181.5 12548.6 12801.8 14991.3 15556.5 15611.2 4544.7 4600.9 4670.8 4683.2 4706.3 11899.5 12047.2 12049.5 12054.9 12060.4 5668.1 5670.5 5692.0 5695.9 5696.2 4658.1 5143.1 5212.8 5237.1 5249.0 3590.3 3735.7 3736.3 3747.2 3814.5 954.3 954.3 961.6 970.8 971.9 949.6 967.9 979.2 983.2 1007.9 6759.3 6906.9 6923.3 6950.9 7003.2 966.9 1002.2 1013.9 1016.2 1025.2 4197.3 4249.3 4263.3 4407.8 4427.9 13398.2 13844.3 13851.4 13914.0 13920.4 12099.0 13487.1 13821.0 13845.6 13851.1 10202.9 10481.6 10498.5 10505.5 10521.0 6745.5 7001.6 7101.3 7175.8 7187.2 12479.0 12685.9 12755.4 12780.8 12826.4 11755.2 12515.2 12769.9 12845.9 13095.1 9235.5 9400.7 9471.5 9519.4 9520.1 10275.7 10324.1 10436.8 10503.9 10532.9 8945.6 9034.4 9075.6 9112.0 9213.2 8776.6 8815.4 8815.5 8891.5 8912.3 6827.6 7854.9 8016.4 8071.9 8072.1 4953.9 5898.6 5949.4 6075.9 6087.4 5633.4 5675.6 5683.0 5715.1 5777.4 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4586.84 Median:4982.15 stddev:1269.55 (25.5%) stddev-sans-first:1233.29 17:44:44 INFO - PROCESS | 1639 | Values: 383.8 385.2 387.9 394.6 398.4 4495.3 5068.9 5146.9 5150.0 5232.5 4355.0 4833.8 5144.5 5155.1 5158.8 3547.7 3828.5 4014.4 4319.5 4343.3 3504.0 3612.6 3835.9 4319.8 4361.1 3848.6 3848.9 4332.6 4368.2 4412.4 3705.3 3969.4 4011.7 4318.0 4341.0 3165.9 3660.3 3973.4 3978.8 3980.1 4125.4 4467.6 4503.8 4731.6 5025.5 4380.6 4391.7 4967.6 5027.9 5082.2 4682.9 4715.6 5670.2 5692.6 5737.3 4129.3 4454.0 4457.3 4465.3 4469.0 5107.6 5192.3 5589.9 5618.8 5863.3 4282.6 4356.4 4824.7 5043.4 5047.9 4589.2 4676.6 5112.9 5152.4 5181.5 4478.3 4787.4 5327.2 5351.3 5353.8 4811.6 4951.5 5245.4 5273.9 5587.6 1577.8 1609.9 1695.5 1738.1 1743.8 1239.6 1240.9 1256.8 1426.3 1440.2 4380.7 4633.2 4936.8 4951.4 5008.8 4841.1 4874.8 5649.9 5672.5 5697.4 4585.7 4770.5 4986.2 5007.6 5016.6 2440.0 2562.3 2655.6 2769.0 2826.1 5023.4 5238.8 5507.7 5674.3 5901.9 5230.0 5390.7 5481.6 5847.7 5860.2 4983.8 5663.9 5903.3 5905.3 5928.8 5721.2 5731.4 5753.1 5830.6 5836.7 5008.3 5489.5 5537.0 5589.0 5642.0 4980.6 5708.4 5847.4 5901.9 5945.9 4227.8 4540.0 4545.0 4555.6 4602.0 5147.5 5424.8 5637.4 5671.7 5764.6 5505.8 5505.9 5529.7 5533.4 5573.6 4672.5 5110.4 5146.1 5409.5 5422.5 4448.4 4644.1 4717.0 4978.2 5380.0 4849.0 5045.5 5191.9 5536.5 5582.5 5135.8 5449.3 5476.0 5533.0 5561.1 17:44:44 INFO - PROCESS | 1639 | 17:44:44 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20317.74 Median:4185.89 stddev:28592.35 (683.1%) stddev-sans-first:28634.45 17:44:44 INFO - PROCESS | 1639 | Values: 658.7 738.6 746.3 794.2 885.4 15555.5 16325.3 16780.8 16798.9 17259.7 15219.3 15549.3 15625.8 15931.4 15958.2 962.1 991.3 994.8 995.0 995.9 955.3 977.2 987.6 991.5 992.0 926.0 1037.3 1039.9 1040.5 1040.9 986.8 1027.6 1034.6 1034.7 1035.7 764.8 772.5 774.4 774.8 774.8 14625.1 16223.1 17022.0 17882.0 18086.3 14160.2 14227.4 14395.9 14399.3 14507.5 10004.2 10228.7 11670.7 11940.9 11960.7 3565.4 3698.9 3877.2 3906.6 3932.1 95386.0 96937.0 99702.5 102095.0 102121.5 10658.9 10667.4 10883.9 11130.9 11506.7 20901.8 21496.8 21504.9 21559.5 21633.8 7816.7 7889.1 7913.9 7938.8 8003.7 4439.6 4462.1 4513.7 4553.5 4562.1 3158.1 3169.6 3218.9 3223.4 3226.2 581.6 617.7 622.7 634.2 636.0 632.3 658.4 692.3 693.9 722.4 9556.9 10484.3 10506.9 10633.2 10668.0 701.0 721.2 722.2 726.0 728.8 4926.8 5135.2 5140.6 5145.4 5146.1 84717.6 86416.0 94788.6 102194.5 103145.0 89630.0 91366.5 91370.0 91767.5 92484.0 77629.0 78965.0 79708.6 80905.0 81326.0 9836.0 9853.3 9906.7 9951.4 9974.5 52674.0 53242.5 53890.0 54448.4 54900.7 66059.0 67097.0 67838.7 68463.0 69558.0 11419.9 11449.6 11565.8 11642.2 12195.5 12906.0 12957.6 13137.7 13206.1 13276.3 12096.6 12132.1 12436.5 12590.2 13374.8 13804.0 14018.9 14458.0 14813.2 14963.6 13405.4 13619.3 13858.5 14069.4 14272.5 8873.4 9234.3 9375.5 9424.4 9960.2 10126.9 10357.3 11460.3 11799.8 12045.2 17:44:44 INFO - PROCESS | 1639 | -------- Summary: end -------- 17:44:44 INFO - PROCESS | 1639 | 17:44:45 INFO - TEST-INFO | 1639: exit 0 17:44:45 INFO - TEST-OK | dromaeo_css | took 1115912ms 17:44:45 INFO - TEST-START | kraken 17:44:45 INFO - operating with platform_type : mac_ 17:44:45 INFO - Initialising browser for kraken test... 17:44:45 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpTGkWqA/profile http://localhost:49227/getInfo.html) 17:44:47 INFO - PROCESS | 1652 | 2017-05-25 17:44:47.875 plugin-container[1654:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 17:44:47 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:47 INFO - PROCESS | 1652 | 2017-05-25 17:44:47.876 plugin-container[1654:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 17:44:47 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:47 INFO - PROCESS | 1652 | 2017-05-25 17:44:47.876 plugin-container[1654:10045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:44:47 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 17:44:47 INFO - PROCESS | 1652 | colorDepth:24 17:44:47 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 17:44:47 INFO - PROCESS | 1652 | __metrics 17:44:48 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 17:44:48 INFO - TEST-INFO | 1652: exit 0 17:44:48 INFO - Browser initialized. 17:44:48 INFO - Running cycle 1/1 for kraken test... 17:44:48 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpTGkWqA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:44:51 INFO - PROCESS | 1655 | 2017-05-25 17:44:51.002 plugin-container[1656:10169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 17:44:51 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:51 INFO - PROCESS | 1655 | 2017-05-25 17:44:51.003 plugin-container[1656:10169] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 17:44:51 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:51 INFO - PROCESS | 1655 | 2017-05-25 17:44:51.003 plugin-container[1656:10169] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:45:00 INFO - PROCESS | 1655 | RSS: Main: 203112448 17:45:00 INFO - PROCESS | 1655 | 17:45:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 17:45:04 INFO - PROCESS | 1655 | RSS: Main: 216608768 17:45:04 INFO - PROCESS | 1655 | 17:45:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 17:45:08 INFO - PROCESS | 1655 | RSS: Main: 224542720 17:45:08 INFO - PROCESS | 1655 | 17:45:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 17:45:11 INFO - PROCESS | 1655 | RSS: Main: 228089856 17:45:11 INFO - PROCESS | 1655 | 17:45:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 17:45:14 INFO - PROCESS | 1655 | RSS: Main: 229617664 17:45:14 INFO - PROCESS | 1655 | 17:45:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:17 INFO - PROCESS | 1655 | RSS: Main: 229490688 17:45:17 INFO - PROCESS | 1655 | 17:45:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:20 INFO - PROCESS | 1655 | RSS: Main: 233947136 17:45:20 INFO - PROCESS | 1655 | 17:45:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:25 INFO - PROCESS | 1655 | RSS: Main: 233586688 17:45:25 INFO - PROCESS | 1655 | 17:45:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 17:45:28 INFO - PROCESS | 1655 | RSS: Main: 233799680 17:45:28 INFO - PROCESS | 1655 | 17:45:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:31 INFO - PROCESS | 1655 | RSS: Main: 233312256 17:45:31 INFO - PROCESS | 1655 | 17:45:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:33 INFO - PROCESS | 1655 | RSS: Main: 233287680 17:45:33 INFO - PROCESS | 1655 | 17:45:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:36 INFO - PROCESS | 1655 | RSS: Main: 233066496 17:45:36 INFO - PROCESS | 1655 | 17:45:39 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:40 INFO - PROCESS | 1655 | RSS: Main: 233512960 17:45:40 INFO - PROCESS | 1655 | 17:45:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:45:44 INFO - PROCESS | 1655 | RSS: Main: 233205760 17:45:44 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 17:45:46 INFO - PROCESS | 1655 | RSS: Main: 236789760 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | __start_tp_report 17:45:46 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 17:45:46 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 17:45:46 INFO - PROCESS | 1655 | |i|pagename|runs| 17:45:46 INFO - PROCESS | 1655 | |0;ai-astar;106;119;120;120;119;120;119;132;174;157 17:45:46 INFO - PROCESS | 1655 | |1;audio-beat-detection;158;112;112;144;112;139;115;115;147;140 17:45:46 INFO - PROCESS | 1655 | |2;audio-dft;194;198;199;203;199;196;197;196;197;197 17:45:46 INFO - PROCESS | 1655 | |3;audio-fft;85;60;62;91;66;63;63;73;76;60 17:45:46 INFO - PROCESS | 1655 | |4;audio-oscillator;83;102;115;74;72;70;89;104;105;68 17:45:46 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;89;90;92;90;98;129;136;90;89 17:45:46 INFO - PROCESS | 1655 | |6;imaging-darkroom;154;114;94;155;102;153;113;97;148;108 17:45:46 INFO - PROCESS | 1655 | |7;imaging-desaturate;128;93;96;94;92;96;93;118;141;141 17:45:46 INFO - PROCESS | 1655 | |8;json-parse-financial;67;70;94;103;105;87;68;66;82;105 17:45:46 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;64;59;62;65;64;59;72;79;82;56 17:45:46 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;103;103;105;101;84;83;83;86;79;89 17:45:46 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;136;138;140;157;153;150;143;156;209;164 17:45:46 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;166;191;158;221;228;169;167;174;211;234 17:45:46 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;75;78;86;90;74;59;76;66;63;85 17:45:46 INFO - PROCESS | 1655 | __end_tp_report 17:45:46 INFO - PROCESS | 1655 | __start_cc_report 17:45:46 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1016 17:45:46 INFO - PROCESS | 1655 | __end_cc_report 17:45:46 INFO - PROCESS | 1655 | __startTimestamp1495759546333__endTimestamp 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | ------- Summary: start ------- 17:45:46 INFO - PROCESS | 1655 | Number of tests: 14 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:128.60 Median:120.00 stddev:20.79 (17.3%) stddev-sans-first:20.37 17:45:46 INFO - PROCESS | 1655 | Values: 106.0 119.0 120.0 120.0 119.0 120.0 119.0 132.0 174.0 157.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:139.50 stddev:17.85 (12.8%) stddev-sans-first:15.65 17:45:46 INFO - PROCESS | 1655 | Values: 158.0 112.0 112.0 144.0 112.0 139.0 115.0 115.0 147.0 140.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:197.60 Median:197.50 stddev:2.41 (1.2%) stddev-sans-first:2.18 17:45:46 INFO - PROCESS | 1655 | Values: 194.0 198.0 199.0 203.0 199.0 196.0 197.0 196.0 197.0 197.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:69.90 Median:69.50 stddev:11.00 (15.8%) stddev-sans-first:10.22 17:45:46 INFO - PROCESS | 1655 | Values: 85.0 60.0 62.0 91.0 66.0 63.0 63.0 73.0 76.0 60.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:71.00 stddev:17.23 (24.3%) stddev-sans-first:18.17 17:45:46 INFO - PROCESS | 1655 | Values: 83.0 102.0 115.0 74.0 72.0 70.0 89.0 104.0 105.0 68.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:99.20 Median:90.00 stddev:17.83 (19.8%) stddev-sans-first:18.53 17:45:46 INFO - PROCESS | 1655 | Values: 89.0 89.0 90.0 92.0 90.0 98.0 129.0 136.0 90.0 89.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:123.80 Median:153.50 stddev:25.53 (16.6%) stddev-sans-first:24.63 17:45:46 INFO - PROCESS | 1655 | Values: 154.0 114.0 94.0 155.0 102.0 153.0 113.0 97.0 148.0 108.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:109.20 Median:93.00 stddev:20.69 (22.2%) stddev-sans-first:20.80 17:45:46 INFO - PROCESS | 1655 | Values: 128.0 93.0 96.0 94.0 92.0 96.0 93.0 118.0 141.0 141.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:84.70 Median:69.00 stddev:16.38 (23.7%) stddev-sans-first:16.08 17:45:46 INFO - PROCESS | 1655 | Values: 67.0 70.0 94.0 103.0 105.0 87.0 68.0 66.0 82.0 105.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:64.50 stddev:8.72 (13.5%) stddev-sans-first:9.21 17:45:46 INFO - PROCESS | 1655 | Values: 64.0 59.0 62.0 65.0 64.0 59.0 72.0 79.0 82.0 56.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:83.00 stddev:10.17 (12.2%) stddev-sans-first:9.91 17:45:46 INFO - PROCESS | 1655 | Values: 103.0 103.0 105.0 101.0 84.0 83.0 83.0 86.0 79.0 89.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:154.50 stddev:21.21 (13.7%) stddev-sans-first:21.40 17:45:46 INFO - PROCESS | 1655 | Values: 136.0 138.0 140.0 157.0 153.0 150.0 143.0 156.0 209.0 164.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.90 Median:201.00 stddev:29.01 (14.4%) stddev-sans-first:29.21 17:45:46 INFO - PROCESS | 1655 | Values: 166.0 191.0 158.0 221.0 228.0 169.0 167.0 174.0 211.0 234.0 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:77.00 stddev:10.21 (13.3%) stddev-sans-first:10.83 17:45:46 INFO - PROCESS | 1655 | Values: 75.0 78.0 86.0 90.0 74.0 59.0 76.0 66.0 63.0 85.0 17:45:46 INFO - PROCESS | 1655 | -------- Summary: end -------- 17:45:46 INFO - PROCESS | 1655 | 17:45:46 INFO - TEST-INFO | 1655: exit 0 17:45:46 INFO - TEST-OK | kraken | took 61826ms 17:45:46 INFO - SUITE-END | took 1177s 17:45:47 INFO - Completed test suite (00:19:38) 17:45:47 INFO - javascript benchmark 17:45:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7713.298231143563, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.5926249956256, 3319.369319829234, 3519.961611324659, 3520.80635565044, 3533.3286675531663, 5948.1375200595885, 5976.147394307768, 6002.249718785158, 6006.849828754283, 6018.15745795589, 5137.666051706637, 5843.64938103714, 6002.099685047245, 6013.669248191348, 6093.969530152354, 5359.705216213107, 5372.623916325859, 5447.754851031713, 5475.206095116206, 5562.16567514874, 5498.835034948958, 5510.889782204354, 5521.1442226454965, 5523.475269849358, 5530.253415788858, 5304.416514183436, 5308.734563271841, 5396.676199428047, 5422.267993820843, 5530.170474428848, 4324.545922678137, 4752.7623618818925, 5434.049041417736, 5446.101393270105, 5546.140348246028, 4565.954340456619, 5371.946280537184, 5397.27136836526, 5458.781648734046, 5508.889822203533, 5850.151727999416, 6076.270847498285, 6082.0, 6112.327643959161, 6134.141220229171, 5290.4974027467715, 5302.469753024705, 6284.214473190851, 6329.8417539561415, 6349.936500635027, 5187.48125187482, 6432.260290066655, 6460.224773027267, 6461.870762584722, 6466.902996455057, 6053.27360716708, 6139.201903752483, 6163.7842675506145, 6185.195924529828, 6202.565820392574, 5381.48875856793, 6236.501079913596, 6337.683115844189, 6400.455961243253, 6429.485641148697, 6257.124002639634, 6290.99344104941, 6299.149614802035, 6319.304876463585, 6320.71556779946, 6364.64994425311, 6400.103985442041, 6421.068945002949, 6424.775132870421, 6439.356064393523, 6215.098810672517, 6261.624302541862, 6296.937030629727, 6353.142325786052, 6396.456301214449, 6253.187085678924, 6274.466670332981, 6292.1505596743855, 6306.747730090745, 6348.238211414659, 4707.81168753246, 4888.877778055577, 4902.877428064256, 4927.827526036572, 4969.055879382906, 4435.334699794992, 4446.644268458515, 4453.643708503377, 4467.061916997402, 4510.939929116655, 5781.653100813881, 5862.062070068769, 5929.495992840657, 6078.483328917003, 6107.877842443127, 6342.746290148342, 6353.936460635427, 6372.6495042773095, 6430.421262086435, 6447.903281450782, 6041.24484439445, 6058.939410605837, 6059.0, 6061.060535616986, 6079.726412311456, 5409.83770486886, 5646.943530564724, 5655.462731040545, 5657.490825825613, 5692.9146062809095, 5610.915836262541, 6418.582792118591, 6428.389303016206, 6463.644499552382, 6544.709351726858, 6467.159269294962, 6475.967620161869, 6499.285078641251, 6500.577462464925, 6512.120863683532, 6491.837704057436, 6647.634380109141, 6655.800325990228, 6667.966660166862, 6668.266490685924, 6235.625862448268, 6267.2165979252595, 6281.371862813682, 6308.08532762756, 6387.329330420239, 6450.41946224842, 6466.0, 6473.708683109366, 6492.253390859923, 6493.707783149675, 4942.827001054946, 6260.436560709558, 6305.716242768995, 6319.652419116993, 6321.841953951188, 5102.540771330598, 6197.411245931629, 6209.2548894133, 6246.219222597176, 6291.433770960635, 4790.0, 6153.5692501526255, 6188.566800324114, 6214.130021797043, 6294.339094395023, 6238.845813524513, 6300.243970723543, 6325.304216536086, 6328.651924144032, 6344.429001389897, 6133.640713961282, 6185.195924529782, 6217.160683307877, 6255.0, 6260.280067792262], "value": 5825.454981138865, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.5758938610298, 763.6143747407558, 821.7493664432345, 823.773462297868, 953.8187744328577, 38715.0, 39457.60542394575, 41182.58817411825, 41193.58806411935, 41279.99999999996, 39689.0, 39857.00357491064, 40005.39991900117, 40159.19681606366, 40319.59680403202, 6904.723811047552, 8342.040665323475, 10987.725306867313, 11256.212065155443, 11437.97058264756, 9761.658341958033, 11051.392173430459, 11105.55577776888, 11226.101911847032, 11233.775324493505, 11212.831807522894, 11246.044086252678, 11251.212415130944, 11267.661970140909, 11273.887261127407, 11173.329600223971, 11196.104311655048, 11235.325880447117, 11241.269317494336, 11265.774684506265, 10980.725481862937, 11024.0, 11037.889621103846, 11041.33751974876, 11052.557897684082, 34856.65143348585, 35021.824890875636, 35060.474092888624, 35184.82407587971, 35262.64737352619, 3819.2552452271816, 3857.8821379262517, 3884.184321292532, 3884.3008258513455, 3930.1353702185475, 25927.35181620456, 26143.73856261451, 26382.86808565964, 27673.616319184162, 27818.0, 2848.886044558215, 3083.260017595783, 3164.019154062248, 3179.4118088153764, 3192.4892017277125, 323483.0, 325226.0, 325588.0, 326953.0, 327211.0, 40558.78323650295, 45124.548754512034, 45244.54755452469, 45248.3212751809, 45388.77305613451, 46490.5350946493, 46696.0, 46717.532824671995, 46751.0, 46770.5322946773, 13571.864281357257, 13996.160230386005, 14002.50991215323, 14008.50970216058, 14032.789508157386, 11926.701832454084, 11975.820362694567, 11992.520299187934, 11998.520059197708, 12011.399430028638, 11829.52681892732, 11850.822237666442, 11862.703432414259, 11865.466054027589, 11976.221545599508, 5722.685252311163, 5762.88474230513, 5770.740316685759, 5775.306963164448, 5791.623544469596, 1111.666500049998, 1136.4601814138218, 1161.7386088130104, 1182.7930112230326, 1195.8699029417292, 27750.722492775218, 27875.30311742182, 28000.87996480159, 28386.716132838406, 29439.0, 1061.2624226162743, 1073.8241625420308, 1097.9945100274447, 1105.729096371378, 1110.2006555280186, 13862.93068534651, 14245.318838552288, 14251.429942802379, 14430.206338651475, 14499.057561258485, 496254.0, 500743.0, 501347.0, 501578.0, 502220.0, 48893.755531222116, 49157.0, 49203.753981229864, 49279.50720492821, 49550.256746148836, 1192.8151136573845, 1235.6469665716027, 1404.5996890886254, 1435.1819462906044, 1437.475321507642, 26626.604752799936, 27331.31671708223, 27457.31356716018, 28287.57568636431, 28376.43247135087, 34196.31607367789, 41511.58488415077, 41665.0, 41668.791656041525, 41774.791126044176, 430234.84882575384, 431224.8438757786, 433162.0, 433317.0, 433729.0, 30302.090937271918, 30392.696073038987, 30530.626121825015, 30534.931277405183, 30629.693703062683, 38700.6129938697, 38800.835974920796, 39336.0, 39993.00017499586, 39993.80003100082, 27498.45003099887, 27905.0, 28007.75937925002, 28206.85896570504, 28930.27674308159, 27736.0, 27744.445111097262, 27860.582091268243, 28427.0, 28686.56970145491, 31806.36387272195, 32458.052516849064, 32552.348953020333, 32690.346193076482, 34539.30921381609, 22331.888340558195, 25677.033088345222, 28545.714542854304, 28697.0, 29590.556141658322, 18388.632227355647, 19754.305144236136, 22307.10771569119, 24119.03523859026, 24434.14480493174], "value": 18244.3173940359, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.2529504229147, 407.5618709886871, 407.6290575576226, 416.86035178215303, 452.3170013279946, 20586.89706551467, 20723.896380518094, 21848.0, 22022.669659955114, 22278.220262290823, 22103.889480552614, 22375.888120559415, 22592.8870355648, 23143.30570082896, 23272.767272327314, 6899.689513971884, 7119.5372300800545, 7132.322429369202, 7171.784846454615, 7218.639068046603, 6849.863002739942, 6873.312668733111, 7004.474664400191, 7087.468439867005, 7128.572285662877, 6894.758683446081, 6914.3777060064585, 7040.29597040297, 7044.260352662974, 7087.220405755363, 6728.93271067288, 7000.579965202104, 7026.086608740882, 7088.9012203108605, 7094.645267736644, 6702.0, 6732.83167920801, 6761.782879081763, 6769.593824370504, 6783.559068660571, 26397.3400664983, 26502.0, 27195.72804271952, 27309.86345068262, 27443.588346174824, 2870.956935645967, 2920.1385591250632, 2935.354222071141, 2946.0425361757516, 2951.6900725423734, 15678.76481852773, 16434.83565164357, 16504.0, 16696.916515417346, 16771.077590732504, 2121.95756084879, 2125.234915430444, 2144.088762276042, 2163.7836216378396, 2165.902534385956, 119474.0, 134041.32979335138, 134315.0, 145161.27419362942, 181556.09221953937, 20427.897860510602, 21568.676469852966, 21762.782372176393, 21811.127554897626, 21846.89076554607, 24879.378015549755, 30499.847500762353, 31005.379892402027, 31159.376812463623, 31270.0, 8396.958015209884, 9889.56601292823, 9932.702018939442, 10079.899201007896, 10142.594296228068, 8262.504249745034, 8280.130586288473, 8281.337493000545, 8306.753986902013, 8330.042045164762, 9547.427154370622, 9699.563519641633, 9739.26955478342, 9766.706998790047, 9792.21662267017, 3731.552213734369, 4101.420952933142, 4151.792410379529, 4159.854405095807, 4171.249175148502, 938.7606160429046, 992.6476100984191, 1080.357187473452, 1090.929089609173, 1125.6397952655236, 21369.931503425076, 21622.567548648938, 21659.891700541397, 21715.34853954384, 21747.347579572637, 1075.908547773448, 1087.6302057300556, 1089.4171618184234, 1115.3976852499559, 1139.4587570903755, 11173.385463799406, 12127.696807579881, 12360.814587781191, 12527.686807829878, 12600.74798504043, 749936.0, 769715.0, 778246.0, 784797.0, 793424.0328798319, 158880.0, 171092.0, 174402.0, 175656.0, 179158.0, 1032.4734385463319, 1273.376045737582, 1278.9345207490023, 1317.9802302965463, 1319.4854006937303, 19045.809541904957, 19363.709544356563, 20146.89926550358, 20149.59700806005, 20269.49326266855, 68694.65652671705, 69299.96050059152, 69511.60976780538, 69671.60656786937, 71459.64270178616, 92940.0, 94027.0, 95795.0, 96913.03086969322, 97541.51229243808, 11354.489047993025, 11663.5917742882, 11695.005924496512, 11763.529458821893, 12056.819147712968, 13186.0, 13246.072774905664, 13656.317184140555, 13666.385012674653, 13780.379882905494, 11401.543938242377, 11551.595694150665, 11586.826197606873, 11661.6501504955, 11786.646400607995, 10771.946140269249, 10780.514876830404, 10827.891721082688, 11153.498092586015, 11204.991550760471, 11329.0, 11570.132240081859, 11588.710282243012, 11670.474828632901, 11740.00209982127, 11733.296002239893, 11832.408379581158, 12041.879581204075, 12125.029997600348, 12129.21160124589, 11549.42252887369, 11579.13156513248, 11629.651110466699, 11928.403579821148, 11943.522259109539], "value": 11930.81778834377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.916480428772, 1563.695079459506, 1566.8824838137143, 1582.857542821146, 1585.128179501274, 10812.891871081296, 11119.833202501968, 11418.600348987788, 11492.942535287322, 11513.48189331481, 11394.316341019545, 11515.596954106608, 11605.941970290136, 11621.360825154614, 11628.186026978115, 2055.8972051397373, 2357.1867705641525, 2387.3554140382084, 2399.892004859777, 2405.158194631874, 2301.2866011536476, 2381.357033600935, 2395.281415575329, 2397.8081753459683, 2399.7360290368047, 2318.6290193569034, 2318.7797159269844, 2319.026009076192, 2391.5695174868515, 2392.629142482918, 2348.941276468085, 2364.5270945810908, 2365.6806331145335, 2380.4405964598304, 2393.9880300598475, 1954.2378472395776, 2014.8186663200383, 2058.8147066763913, 2309.976900230993, 2343.0, 11326.433678316134, 11341.546338146465, 11405.885941140565, 11412.600558980395, 11427.714307142305, 5582.972085139589, 5695.202671625976, 5740.339860916007, 5749.540036797088, 5767.798127065533, 3152.7793054486197, 3220.22714548509, 3377.7635565510423, 3571.5535558055244, 3588.9641103588892, 1139.9430028498625, 1149.8673806300787, 1165.7399763271287, 1172.6775136837352, 1181.5273890443805, 12548.560800372034, 12801.807972880415, 14991.32539035746, 15556.455524056604, 15611.219439028004, 4544.7045942013665, 4600.930986035212, 4670.813167473263, 4683.227267500893, 4706.3411122442885, 11899.464524096436, 12047.216930899463, 12049.457774400173, 12054.879451205376, 12060.396980150957, 5668.149777533403, 5670.5463562914865, 5692.0323544997445, 5695.943040569624, 5696.174054762037, 4658.114958157939, 5143.099957507496, 5212.765425555859, 5237.135872580981, 5248.973755131276, 3590.281943611236, 3735.6637902588895, 3736.308782875177, 3747.2130852521277, 3814.5422549294262, 954.3081266082035, 954.3367359684952, 961.5528779117744, 970.834958057132, 971.8833739951251, 949.6343907595488, 967.9322447428613, 979.2215188924822, 983.188869182927, 1007.8941711120374, 6759.256481787, 6906.896396554056, 6923.307669233137, 6950.930490695028, 7003.159620845531, 966.9129778319985, 1002.1782138646386, 1013.9036791504795, 1016.2124353625999, 1025.1849779425227, 4197.349410841324, 4249.2563801334645, 4263.339182426729, 4407.757573333433, 4427.889302767392, 13398.196108233537, 13844.30778461073, 13851.376688049062, 13913.956453266046, 13920.37358318878, 12099.0, 13487.055906086676, 13820.96342774296, 13845.584632461043, 13851.099678520864, 10202.897971020195, 10481.580736770597, 10498.47507624631, 10505.52725127371, 10521.0, 6745.494087943326, 7001.579905205704, 7101.2898710128575, 7175.784726458214, 7187.209406965334, 12479.0, 12685.873141268838, 12755.426005829575, 12780.808287875876, 12826.358682065673, 11755.177137600285, 12515.186512876633, 12769.872301276868, 12845.871541284467, 13095.148815326973, 9235.538223088684, 9400.717978460658, 9471.47906865129, 9519.381240219363, 9520.095590918852, 10275.7431064224, 10324.07083362501, 10436.843447348449, 10503.949605039741, 10532.947335263274, 8945.552722363725, 9034.3675942686, 9075.591598378222, 9111.954440227757, 9213.216876565299, 8776.605052772513, 8815.382923195315, 8815.515146666994, 8891.466512009041, 8912.287017038492, 6827.6244806536115, 7854.882176767467, 8016.358691304798, 8071.878921816295, 8072.071711753224, 4953.851384458328, 5898.616589921641, 5949.405059494016, 6075.878482430238, 6087.421694938974, 5633.436656334334, 5675.602707810579, 5682.971585142048, 5715.114157305624, 5777.364489906191], "value": 5124.994742148497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.77165586476053, 385.19878652403, 387.9456876037355, 394.6448196623037, 398.3705744923022, 4495.348174514694, 5068.923966140511, 5146.864051418656, 5150.0, 5232.529072383485, 4355.0, 4833.78247978841, 5144.485551444854, 5155.123628983072, 5158.819441319554, 3547.7339199560006, 3828.4831547741114, 4014.357702767558, 4319.503257125431, 4343.305071188611, 3503.982480087596, 3612.566492020961, 3835.9424608630893, 4319.82720691172, 4361.146512732092, 3848.634379733921, 3848.903777405559, 4332.631726303268, 4368.226176377991, 4412.426384570017, 3705.277470893177, 3969.4244334571326, 4011.65900898424, 4317.963688714717, 4341.023269764293, 3165.9050228493184, 3660.2979614479395, 3973.4238535412205, 3978.781167035812, 3980.124372638015, 4125.411716489143, 4467.620252278561, 4503.774811259456, 4731.640535219998, 5025.497450254945, 4380.561943805626, 4391.670624703128, 4967.615544538642, 5027.874303142451, 5082.237664350377, 4682.852701088221, 4715.599174070208, 5670.234518340013, 5692.601517893749, 5737.340205876294, 4129.318662420697, 4453.977730111329, 4457.3091170868565, 4465.263231566788, 4469.028096315946, 5107.627762862909, 5192.324997750268, 5589.860253493654, 5618.775248990036, 5863.299643103494, 4282.550332215135, 4356.368326592689, 4824.6863953842885, 5043.36957880265, 5047.949520504748, 4589.196890544142, 4676.625869930397, 5112.948870511322, 5152.407473140562, 5181.476595914814, 4478.328250762412, 4787.42550893895, 5327.227552004938, 5351.3043304370185, 5353.785848566014, 4811.628685824593, 4951.529604687549, 5245.396779370345, 5273.894522109613, 5587.631030397579, 1577.826439091699, 1609.88730788846, 1695.465928232603, 1738.1048759888668, 1743.8256174382714, 1239.5971309324505, 1240.8573014103495, 1256.7989121740477, 1426.3296250762126, 1440.2006886178062, 4380.6933514653665, 4633.212353899845, 4936.777844996984, 4951.3810773653295, 5008.8229266122435, 4841.080194762983, 4874.82938097172, 5649.858753531194, 5672.50409819678, 5697.4302569742695, 4585.70192937458, 4770.499097594772, 4986.202207646759, 5007.599392048627, 5016.648834581544, 2440.025112089898, 2562.256945485788, 2655.6058069513274, 2768.986155069252, 2826.1239015904725, 5023.422306434733, 5238.813574561157, 5507.669539827623, 5674.347450043298, 5901.911471327933, 5229.980153870035, 5390.687450039953, 5481.588880833953, 5847.736851841677, 5860.238169037998, 4983.750812459362, 5663.886722265532, 5903.26209223847, 5905.291365036223, 5928.822135335947, 5721.199032135588, 5731.3665605302285, 5753.101476512825, 5830.591858569859, 5836.737346819318, 5008.298838162733, 5489.451054894478, 5536.97231513856, 5588.954020059159, 5642.040853054908, 4980.555638864688, 5708.400617935057, 5847.44449277318, 5901.881962360643, 5945.881082378241, 4227.837344730101, 4540.0, 4545.0, 4555.589996900295, 4602.0, 5147.459516750687, 5424.755885985219, 5637.43625637433, 5671.716414179357, 5764.596478246483, 5505.752241149078, 5505.889882202254, 5529.723513824373, 5533.418991005887, 5573.575494659836, 4672.462666793363, 5110.437851836369, 5146.114683413998, 5409.539424355481, 5422.457754224546, 4448.421705178306, 4644.142343062802, 4716.98584804255, 4978.228374601942, 5380.0, 4848.951510484992, 5045.470225626404, 5191.883744994844, 5536.511997441586, 5582.525485333792, 5135.845924622267, 5449.3188351456065, 5476.0, 5532.960938514826, 5561.110222364402], "value": 4232.138400218414, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.7035833874758, 738.5827007740629, 746.3059354800035, 794.150259222632, 885.4244740918402, 15555.533333999983, 16325.265363058661, 16780.83219167808, 16798.91600541997, 17259.654806903854, 15219.315130819112, 15549.300281487333, 15625.765613515807, 15931.362745490167, 15958.202089895487, 962.1340793286029, 991.3308516751164, 994.8408254679252, 995.0348162282611, 995.8655581496478, 955.2739917662592, 977.2426369563609, 987.6247026130061, 991.504247876062, 992.0, 925.9907400925972, 1037.2998226197324, 1039.8960103989577, 1040.4537617750666, 1040.9115225205867, 986.7631768375574, 1027.609508386812, 1034.6378767431338, 1034.7413146713284, 1035.6996471023394, 764.7973287078928, 772.5055964182927, 774.3766268154134, 774.7636970723927, 774.8295375017487, 14625.122492650475, 16223.107729074896, 17022.0, 17882.0, 18086.276548938025, 14160.221187834664, 14227.35976881043, 14395.856041439556, 14399.280035998263, 14507.492237771627, 10004.22433715176, 10228.744281393025, 11670.708232294175, 11940.88059119415, 11960.72006592995, 3565.3938830398893, 3698.9445158322646, 3877.2051729395407, 3906.5507466641416, 3932.134930315326, 95386.0, 96937.0, 99702.50148749282, 102095.0, 102121.48939255331, 10658.946705266424, 10667.359958402521, 10883.891161088288, 11130.942560456744, 11506.654800356002, 20901.79098209029, 21496.78503214979, 21504.892475537836, 21559.46101347479, 21633.810140442427, 7816.7264145754625, 7889.09275433321, 7913.920860791433, 7938.841223175504, 8003.719869804529, 4439.644828413784, 4462.129884672458, 4513.68404211702, 4553.54464553542, 4562.064776720765, 3158.0841555948978, 3169.5879535660215, 3218.935621287561, 3223.3875563642832, 3226.209578653245, 581.5609215042616, 617.6973283091253, 622.6948795090373, 634.1850721822436, 636.0332294911801, 632.3297304856775, 658.4469046001382, 692.2834865913856, 693.8646963842002, 722.37514549915, 9556.904430955741, 10484.318519296221, 10506.894931050745, 10633.17822057828, 10668.0, 700.9684564194623, 721.1850608812114, 722.1767185408638, 725.992740072603, 728.8141523911369, 4926.802927882844, 5135.152699804565, 5140.614453915971, 5145.434002259748, 5146.073706732824, 84717.57641211755, 86416.0, 94788.57817132749, 102194.48902755439, 103145.0, 89630.0, 91366.54316728507, 91370.0, 91767.54116229509, 92484.0, 77629.0, 78965.0, 79708.60145699234, 80905.0, 81326.0, 9835.950820245853, 9853.261005424765, 9906.653267135616, 9951.402915825074, 9974.503824426394, 52674.0, 53242.46757532375, 53890.0, 54448.36654900359, 54900.72549637226, 66059.0, 67096.99354509784, 67838.66080669565, 68463.0, 69558.0, 11419.942900285445, 11449.59926402572, 11565.768684626091, 11642.185047046625, 12195.51217951272, 12906.0, 12957.611271661865, 13137.671558210739, 13206.066814922242, 13276.26980516081, 12096.637100886988, 12132.090093243218, 12436.502539898665, 12590.24458532491, 13374.799378009144, 13804.0, 14018.859811402164, 14458.0, 14813.185274809557, 14963.551093467213, 13405.396757145756, 13619.25094119833, 13858.548798928996, 14069.366878490287, 14272.500462483764, 8873.378863479753, 9234.261259099121, 9375.484348360633, 9424.387414818015, 9960.203183745429, 10126.949365253127, 10357.274990750575, 11460.255083419552, 11799.764004719686, 12045.156839021534], "value": 7656.297000889079, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 120.0, 120.0, 119.0, 120.0, 119.0, 132.0, 174.0, 157.0], "value": 128.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 112.0, 112.0, 144.0, 112.0, 139.0, 115.0, 115.0, 147.0, 140.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 198.0, 199.0, 203.0, 199.0, 196.0, 197.0, 196.0, 197.0, 197.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 62.0, 91.0, 66.0, 63.0, 63.0, 73.0, 76.0, 60.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 102.0, 115.0, 74.0, 72.0, 70.0, 89.0, 104.0, 105.0, 68.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 92.0, 90.0, 98.0, 129.0, 136.0, 90.0, 89.0], "value": 99.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 114.0, 94.0, 155.0, 102.0, 153.0, 113.0, 97.0, 148.0, 108.0], "value": 123.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 93.0, 96.0, 94.0, 92.0, 96.0, 93.0, 118.0, 141.0, 141.0], "value": 109.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 94.0, 103.0, 105.0, 87.0, 68.0, 66.0, 82.0, 105.0], "value": 84.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 59.0, 62.0, 65.0, 64.0, 59.0, 72.0, 79.0, 82.0, 56.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 103.0, 105.0, 101.0, 84.0, 83.0, 83.0, 86.0, 79.0, 89.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 140.0, 157.0, 153.0, 150.0, 143.0, 156.0, 209.0, 164.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 191.0, 158.0, 221.0, 228.0, 169.0, 167.0, 174.0, 211.0, 234.0], "value": 191.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 86.0, 90.0, 74.0, 59.0, 76.0, 66.0, 63.0, 85.0], "value": 75.2, "unit": "score"}]}]} 17:45:47 INFO - Return code: 0 17:45:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:45:47 ERROR - # TBPL SUCCESS # 17:45:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:47 INFO - 2017-05-25 17:45:47.615 screenresolution[1660:10559] starting screenresolution argv=screenresolution get 17:45:47 INFO - 2017-05-25 17:45:47.617 screenresolution[1660:10559] Display 0: 1600x1200x32@60 17:45:47 INFO - 2017-05-25 17:45:47.623 screenresolution[1661:10562] starting screenresolution argv=screenresolution list 17:45:47 INFO - Available Modes on Display 0 17:45:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:47 INFO - Intel Iris: 17:45:47 INFO - Chipset Model: Intel Iris 17:45:47 INFO - Type: GPU 17:45:47 INFO - Bus: Built-In 17:45:47 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:47 INFO - Vendor: Intel (0x8086) 17:45:47 INFO - Device ID: 0x0a2e 17:45:47 INFO - Revision ID: 0x0009 17:45:47 INFO - Displays: 17:45:47 INFO - Display: 17:45:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:47 INFO - Display Serial Number: 1600x1200 60 17:45:47 INFO - Main Display: Yes 17:45:47 INFO - Mirror: Off 17:45:47 INFO - Online: Yes 17:45:47 INFO - Rotation: Supported 17:45:47 INFO - Return code: 0 17:45:47 INFO - Running post-action listener: _resource_record_post_action 17:45:47 INFO - [mozharness: 2017-05-26 00:45:47.855875Z] Finished run-tests step (success) 17:45:47 INFO - Running post-run listener: _resource_record_post_run 17:45:47 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 40010752; Write bytes: 513539072; Read time: 253; Write time: 1604 17:45:47 INFO - TinderboxPrint: CPU usage
54.6% 17:45:47 INFO - TinderboxPrint: I/O read bytes / time
40,010,752 / 253 17:45:47 INFO - TinderboxPrint: I/O write bytes / time
513,539,072 / 1,604 17:45:47 INFO - TinderboxPrint: CPU idle
2,180.2 (45.4%) 17:45:47 INFO - TinderboxPrint: CPU system
962.8 (20.1%) 17:45:47 INFO - TinderboxPrint: CPU user
1,654.8 (34.5%) 17:45:47 INFO - TinderboxPrint: Swap in / out
249,176,064 / 0 17:45:47 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204276224; Write bytes: 202721280; Read time: 15604; Write time: 407 17:45:47 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 37954560; Write bytes: 300012544; Read time: 232; Write time: 1162 17:45:48 INFO - Running post-run listener: _upload_blobber_files 17:45:48 INFO - Blob upload gear active. 17:45:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:45:48 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 17:45:48 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'] 17:45:48 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 17:45:48 INFO - (blobuploader) - INFO - Open directory for files ... 17:45:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:45:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:45:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:49 INFO - (blobuploader) - INFO - Done attempting. 17:45:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:45:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:45:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:50 INFO - (blobuploader) - INFO - Done attempting. 17:45:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:45:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:45:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:51 INFO - (blobuploader) - INFO - Done attempting. 17:45:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:45:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:45:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:53 INFO - (blobuploader) - INFO - Done attempting. 17:45:53 INFO - (blobuploader) - INFO - Iteration through files over. 17:45:53 INFO - Return code: 0 17:45:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:45:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:45:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/408f9ba29f373d6e5f048e1beeca7e9eae576c9e5d5c4d407e3738f4e972eee8faa64fc07f72230ce2eed901ce2500fe7b028097a257ba43a7a3b6b72c746368", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4277be35bb24b0a00e2bc8eb879381abe2bcf2c7192513ea3c7f316a976237105c539e5efca4763ec53f01b4ecec80e0c845a79cc077e6ace1c171d1ddf0d14f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c00b68741f428c80ce10b41694f59d861994b4183d9ad605ce9afcb34191edf354453b5270d81de4b61975807844b1a459dae1c8f428da80fe464d3aa9f34098", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81f5b968e6ffcfb0c97227037b63fabf060ebee2a86cf76366e11dcbebceb1d302cb31ca959533b0a46a2a68afc626f45dc1a4cdacae805cbdf1223422eb4fc3"} 17:45:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:45:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:45:53 INFO - Contents: 17:45:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/408f9ba29f373d6e5f048e1beeca7e9eae576c9e5d5c4d407e3738f4e972eee8faa64fc07f72230ce2eed901ce2500fe7b028097a257ba43a7a3b6b72c746368", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4277be35bb24b0a00e2bc8eb879381abe2bcf2c7192513ea3c7f316a976237105c539e5efca4763ec53f01b4ecec80e0c845a79cc077e6ace1c171d1ddf0d14f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c00b68741f428c80ce10b41694f59d861994b4183d9ad605ce9afcb34191edf354453b5270d81de4b61975807844b1a459dae1c8f428da80fe464d3aa9f34098", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81f5b968e6ffcfb0c97227037b63fabf060ebee2a86cf76366e11dcbebceb1d302cb31ca959533b0a46a2a68afc626f45dc1a4cdacae805cbdf1223422eb4fc3"} 17:45:53 INFO - Running post-run listener: copy_logs_to_upload_dir 17:45:53 INFO - Copying logs to upload dir... 17:45:53 INFO - mkdir: /builds/slave/test/build/upload/logs 17:45:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.522953 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-25 17:45:53.748095) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:45:53.751215) ========= 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.IkdALBoUQm/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.7g7JNzBVz1/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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/408f9ba29f373d6e5f048e1beeca7e9eae576c9e5d5c4d407e3738f4e972eee8faa64fc07f72230ce2eed901ce2500fe7b028097a257ba43a7a3b6b72c746368", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4277be35bb24b0a00e2bc8eb879381abe2bcf2c7192513ea3c7f316a976237105c539e5efca4763ec53f01b4ecec80e0c845a79cc077e6ace1c171d1ddf0d14f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c00b68741f428c80ce10b41694f59d861994b4183d9ad605ce9afcb34191edf354453b5270d81de4b61975807844b1a459dae1c8f428da80fe464d3aa9f34098", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81f5b968e6ffcfb0c97227037b63fabf060ebee2a86cf76366e11dcbebceb1d302cb31ca959533b0a46a2a68afc626f45dc1a4cdacae805cbdf1223422eb4fc3"} build_url:https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011023 build_url: 'https://queue.taskcluster.net/v1/task/fo_R6oImRAaHVLdlccbJMg/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/408f9ba29f373d6e5f048e1beeca7e9eae576c9e5d5c4d407e3738f4e972eee8faa64fc07f72230ce2eed901ce2500fe7b028097a257ba43a7a3b6b72c746368", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4277be35bb24b0a00e2bc8eb879381abe2bcf2c7192513ea3c7f316a976237105c539e5efca4763ec53f01b4ecec80e0c845a79cc077e6ace1c171d1ddf0d14f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c00b68741f428c80ce10b41694f59d861994b4183d9ad605ce9afcb34191edf354453b5270d81de4b61975807844b1a459dae1c8f428da80fe464d3aa9f34098", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81f5b968e6ffcfb0c97227037b63fabf060ebee2a86cf76366e11dcbebceb1d302cb31ca959533b0a46a2a68afc626f45dc1a4cdacae805cbdf1223422eb4fc3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:45:53.784513) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:45:53.784835) ========= 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.IkdALBoUQm/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.7g7JNzBVz1/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.005281 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:45:53.832491) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:45:53.832843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:45:53.833152) ========= ========= Total master_lag: 0.76 =========