builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0306 starttime: 1494951918.66 results: success (0) buildid: 20170516074024 builduid: 02f9ef755fb241409788cbc9682986bf revision: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.655742) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.656165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.656448) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.682554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:18.682888) ========= 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.Cy90ASw9gg/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.GFbionTVE9/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-16 09:25:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-05-16 09:25:19 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.508163 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:19.214480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:19.214789) ========= 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.Cy90ASw9gg/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.GFbionTVE9/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.040590 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:19.276545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 09:25:19.276977) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 --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 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cy90ASw9gg/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.GFbionTVE9/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-16 09:25:19,386 truncating revision to first 12 chars 2017-05-16 09:25:19,386 Setting DEBUG logging. 2017-05-16 09:25:19,386 attempt 1/10 2017-05-16 09:25:19,386 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/13ff34d82062?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 09:25:21,371 unpacking tar archive at: mozilla-beta-13ff34d82062/testing/mozharness/ program finished with exit code 0 elapsedTime=2.253751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 09:25:21.553194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.553569) ========= script_repo_revision: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.553952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.554214) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 09:25:21.572690) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-16 09:25:21.573014) ========= /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.Cy90ASw9gg/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.GFbionTVE9/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:25:21 INFO - MultiFileLogger online at 20170516 09:25:21 in /builds/slave/test 09:25:21 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 09:25:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:25:21 INFO - {'append_to_log': False, 09:25:21 INFO - 'base_work_dir': '/builds/slave/test', 09:25:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:25:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:25:21 INFO - 'branch': 'Mozilla-Beta', 09:25:21 INFO - 'buildbot_json_path': 'buildprops.json', 09:25:21 INFO - 'config_files': ('talos/mac_config.py',), 09:25:21 INFO - 'default_actions': ('clobber', 09:25:21 INFO - 'read-buildbot-config', 09:25:21 INFO - 'download-and-extract', 09:25:21 INFO - 'populate-webroot', 09:25:21 INFO - 'create-virtualenv', 09:25:21 INFO - 'install', 09:25:21 INFO - 'run-tests'), 09:25:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:25:21 INFO - 'download_minidump_stackwalk': True, 09:25:21 INFO - 'download_symbols': 'ondemand', 09:25:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:25:21 INFO - 'tooltool.py': '/tools/tooltool.py', 09:25:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:25:21 INFO - '/tools/misc-python/virtualenv.py')}, 09:25:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:25:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:25:21 INFO - 'gecko_profile': False, 09:25:21 INFO - 'gecko_profile_interval': 0, 09:25:21 INFO - 'installer_path': 'installer.exe', 09:25:21 INFO - 'log_level': 'info', 09:25:21 INFO - 'log_name': 'talos', 09:25:21 INFO - 'log_to_console': True, 09:25:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:25:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:25:21 INFO - 'opt_config_files': (), 09:25:21 INFO - 'pip_index': False, 09:25:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:21 INFO - 'cmd': ('bash', 09:25:21 INFO - '-c', 09:25:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:21 INFO - 'enabled': True, 09:25:21 INFO - 'halt_on_failure': False, 09:25:21 INFO - 'name': 'check_screen_resolution'},), 09:25:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:21 INFO - 'cmd': ('bash', 09:25:21 INFO - '-c', 09:25:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:21 INFO - 'enabled': True, 09:25:21 INFO - 'halt_on_failure': False, 09:25:21 INFO - 'name': 'check_screen_resolution'},), 09:25:21 INFO - 'run_cmd_checks_enabled': True, 09:25:21 INFO - 'suite': 'dromaeojs-e10s', 09:25:21 INFO - 'system_bits': '32', 09:25:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:25:21 INFO - 'title': 't-yosemite-r7-0306', 09:25:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:25:21 INFO - 'use_talos_json': True, 09:25:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:25:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:25:21 INFO - 'work_dir': 'build'} 09:25:21 INFO - [mozharness: 2017-05-16 16:25:21.789795Z] Running clobber step. 09:25:21 INFO - Running pre-action listener: _resource_record_pre_action 09:25:21 INFO - Running main action method: clobber 09:25:21 INFO - rmtree: /builds/slave/test/build 09:25:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:25:24 INFO - Running post-action listener: _resource_record_post_action 09:25:24 INFO - [mozharness: 2017-05-16 16:25:24.276172Z] Finished clobber step (success) 09:25:24 INFO - [mozharness: 2017-05-16 16:25:24.276248Z] Running read-buildbot-config step. 09:25:24 INFO - Running pre-action listener: _resource_record_pre_action 09:25:24 INFO - Running main action method: read_buildbot_config 09:25:24 INFO - Using buildbot properties: 09:25:24 INFO - { 09:25:24 INFO - "project": "", 09:25:24 INFO - "product": "firefox", 09:25:24 INFO - "script_repo_revision": "production", 09:25:24 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:25:24 INFO - "repository": "", 09:25:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:25:24 INFO - "buildid": "20170516074024", 09:25:24 INFO - "pgo_build": "False", 09:25:24 INFO - "basedir": "/builds/slave/test", 09:25:24 INFO - "buildnumber": 6, 09:25:24 INFO - "slavename": "t-yosemite-r7-0306", 09:25:24 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:25:24 INFO - "platform": "yosemite_r7", 09:25:24 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:25:24 INFO - "branch": "mozilla-beta", 09:25:24 INFO - "repo_path": "releases/mozilla-beta", 09:25:24 INFO - "revision": "13ff34d820621cdaa52a9cf2dfd2a66998438bf8", 09:25:24 INFO - "stage_platform": "macosx64", 09:25:24 INFO - "builduid": "02f9ef755fb241409788cbc9682986bf", 09:25:24 INFO - "slavebuilddir": "test" 09:25:24 INFO - } 09:25:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:25:24 INFO - Running post-action listener: _resource_record_post_action 09:25:24 INFO - [mozharness: 2017-05-16 16:25:24.279645Z] Finished read-buildbot-config step (success) 09:25:24 INFO - [mozharness: 2017-05-16 16:25:24.279750Z] Running download-and-extract step. 09:25:24 INFO - Running pre-action listener: _resource_record_pre_action 09:25:24 INFO - Running main action method: download_and_extract 09:25:24 INFO - mkdir: /builds/slave/test/build/tests 09:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:24 INFO - trying https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:25:24 INFO - Downloading https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/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 09:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/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 09:25:25 INFO - Downloaded 1287 bytes. 09:25:25 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:25:25 INFO - Using the following test package requirements: 09:25:25 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:25:25 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:25:25 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:25:25 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:25:25 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:25:25 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:25:25 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:25:25 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:25:25 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:25:25 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:25:25 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:25:25 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:25:25 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:25:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:25:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:25:25 INFO - Fetch https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:25:27 INFO - Expected file size: 51224753 09:25:27 INFO - Obtained file size: 51224753 09:25:43 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:25:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:25:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:25:43 INFO - Fetch https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:25:44 INFO - Expected file size: 51224753 09:25:44 INFO - Obtained file size: 51224753 09:25:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:25:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:25:59 INFO - Fetch https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:26:00 INFO - Expected file size: 14404449 09:26:00 INFO - Obtained file size: 14404449 09:26:00 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')]} 09:26:00 INFO - trying https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:26:00 INFO - Downloading https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/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 09:26:02 INFO - Downloaded 59541045 bytes. 09:26:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:26:02 INFO - mkdir: /builds/slave/test/properties 09:26:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:02 INFO - Contents: 09:26:02 INFO - build_url:https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:26:03 INFO - Running post-action listener: _resource_record_post_action 09:26:03 INFO - Running post-action listener: set_extra_try_arguments 09:26:03 INFO - [mozharness: 2017-05-16 16:26:03.791936Z] Finished download-and-extract step (success) 09:26:03 INFO - [mozharness: 2017-05-16 16:26:03.792070Z] Running populate-webroot step. 09:26:03 INFO - Running pre-action listener: _resource_record_pre_action 09:26:03 INFO - Running main action method: populate_webroot 09:26:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:03 INFO - '--tppagecycles', 09:26:03 INFO - '7'], 09:26:03 INFO - 'tests': ['tsvgm']}}, 09:26:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['damp', 'tps']}, 09:26:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'tests': ['damp', 'tps']}, 09:26:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['dromaeo_dom']}, 09:26:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['basic_compositor_video']}, 09:26:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:26:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['a11yr', 09:26:03 INFO - 'ts_paint', 09:26:03 INFO - 'tpaint', 09:26:03 INFO - 'sessionrestore', 09:26:03 INFO - 'sessionrestore_no_auto_restore', 09:26:03 INFO - 'tabpaint']}, 09:26:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:03 INFO - 'ts_paint', 09:26:03 INFO - 'tpaint', 09:26:03 INFO - 'sessionrestore', 09:26:03 INFO - 'sessionrestore_no_auto_restore', 09:26:03 INFO - 'tabpaint']}, 09:26:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:03 INFO - 'ts_paint', 09:26:03 INFO - 'tpaint', 09:26:03 INFO - 'sessionrestore', 09:26:03 INFO - 'sessionrestore_no_auto_restore', 09:26:03 INFO - 'tabpaint']}, 09:26:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:03 INFO - 'ts_paint', 09:26:03 INFO - 'tpaint', 09:26:03 INFO - 'sessionrestore', 09:26:03 INFO - 'sessionrestore_no_auto_restore', 09:26:03 INFO - 'tabpaint']}, 09:26:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['a11yr', 09:26:03 INFO - 'ts_paint', 09:26:03 INFO - 'tpaint', 09:26:03 INFO - 'sessionrestore', 09:26:03 INFO - 'sessionrestore_no_auto_restore', 09:26:03 INFO - 'tabpaint']}, 09:26:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['a11yr', 09:26:03 INFO - 'ts_paint', 09:26:03 INFO - 'tpaint', 09:26:03 INFO - 'sessionrestore', 09:26:03 INFO - 'sessionrestore_no_auto_restore', 09:26:03 INFO - 'tabpaint']}, 09:26:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['tsvgx', 09:26:03 INFO - 'tsvgr_opacity', 09:26:03 INFO - 'tart', 09:26:03 INFO - 'tscrollx', 09:26:03 INFO - 'cart', 09:26:03 INFO - 'tsvg_static']}, 09:26:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:03 INFO - 'tsvgr_opacity', 09:26:03 INFO - 'tart', 09:26:03 INFO - 'tscrollx', 09:26:03 INFO - 'cart', 09:26:03 INFO - 'tsvg_static']}, 09:26:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'talos_options': ['--disable-e10s'], 09:26:03 INFO - 'tests': ['tp5o']}, 09:26:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:26:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'talos_options': ['--disable-e10s', 09:26:03 INFO - '--xperf_path', 09:26:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:03 INFO - 'tests': ['tp5n']}, 09:26:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:26:03 INFO - 'talos_options': ['--xperf_path', 09:26:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:03 INFO - 'tests': ['tp5n']}}, 09:26:03 INFO - 'talos.zip': {'path': '', 09:26:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:03 INFO - Running post-action listener: _resource_record_post_action 09:26:03 INFO - [mozharness: 2017-05-16 16:26:03.810197Z] Finished populate-webroot step (success) 09:26:03 INFO - [mozharness: 2017-05-16 16:26:03.810315Z] Running create-virtualenv step. 09:26:03 INFO - Running pre-action listener: _resource_record_pre_action 09:26:03 INFO - Running main action method: create_virtualenv 09:26:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:03 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 09:26:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:03 INFO - Using real prefix '/tools/python27' 09:26:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:04 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:07 INFO - Installing pip.................done. 09:26:07 INFO - Return code: 0 09:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:26:07 INFO - Reading from file tmpfile_stdout 09:26:07 INFO - Output received: 09:26:07 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) 09:26:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:07 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 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:07 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 09:26:07 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 09:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:07 INFO - 'HOME': '/Users/cltbld', 09:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:07 INFO - 'LOGNAME': 'cltbld', 09:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:07 INFO - 'MOZ_NO_REMOTE': '1', 09:26:07 INFO - 'NO_EM_RESTART': '1', 09:26:07 INFO - 'PAGER': '/bin/cat', 09:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:07 INFO - 'PWD': '/builds/slave/test', 09:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:07 INFO - 'SHELL': '/bin/bash', 09:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:07 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:07 INFO - 'USER': 'cltbld', 09:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:07 INFO - 'XPC_FLAGS': '0x0', 09:26:07 INFO - 'XPC_SERVICE_NAME': '0', 09:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:07 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 09:26:07 INFO - Cleaning up... 09:26:08 INFO - Return code: 0 09:26:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09: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')]} 09:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:08 INFO - 'HOME': '/Users/cltbld', 09:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:08 INFO - 'LOGNAME': 'cltbld', 09:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:08 INFO - 'MOZ_NO_REMOTE': '1', 09:26:08 INFO - 'NO_EM_RESTART': '1', 09:26:08 INFO - 'PAGER': '/bin/cat', 09:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:08 INFO - 'PWD': '/builds/slave/test', 09:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:08 INFO - 'SHELL': '/bin/bash', 09:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:08 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:08 INFO - 'USER': 'cltbld', 09:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:08 INFO - 'XPC_FLAGS': '0x0', 09:26:08 INFO - 'XPC_SERVICE_NAME': '0', 09:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:08 INFO - Downloading/unpacking psutil>=3.1.1 09:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:10 INFO - Installing collected packages: psutil 09:26:10 INFO - Running setup.py install for psutil 09:26:10 INFO - building 'psutil._psutil_osx' extension 09:26:10 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 09:26:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:26:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:26:11 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 09:26:11 INFO - building 'psutil._psutil_posix' extension 09:26:11 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 09:26:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:11 INFO - ^ 09:26:11 INFO - 1 warning generated. 09:26:11 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 09:26:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:11 INFO - Successfully installed psutil 09:26:11 INFO - Cleaning up... 09:26:11 INFO - Return code: 0 09:26:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:11 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 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:11 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 09:26:11 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 09:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:11 INFO - 'HOME': '/Users/cltbld', 09:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:11 INFO - 'LOGNAME': 'cltbld', 09:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:11 INFO - 'MOZ_NO_REMOTE': '1', 09:26:11 INFO - 'NO_EM_RESTART': '1', 09:26:11 INFO - 'PAGER': '/bin/cat', 09:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:11 INFO - 'PWD': '/builds/slave/test', 09:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:11 INFO - 'SHELL': '/bin/bash', 09:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:11 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:11 INFO - 'USER': 'cltbld', 09:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:11 INFO - 'XPC_FLAGS': '0x0', 09:26:11 INFO - 'XPC_SERVICE_NAME': '0', 09:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:13 INFO - Installing collected packages: mozsystemmonitor 09:26:13 INFO - Running setup.py install for mozsystemmonitor 09:26:13 INFO - Successfully installed mozsystemmonitor 09:26:13 INFO - Cleaning up... 09:26:13 INFO - Return code: 0 09:26:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:13 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')]} 09:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:13 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')]} 09:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:13 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 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:13 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 09:26:13 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 09:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:13 INFO - 'HOME': '/Users/cltbld', 09:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:13 INFO - 'LOGNAME': 'cltbld', 09:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:13 INFO - 'MOZ_NO_REMOTE': '1', 09:26:13 INFO - 'NO_EM_RESTART': '1', 09:26:13 INFO - 'PAGER': '/bin/cat', 09:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:13 INFO - 'PWD': '/builds/slave/test', 09:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:13 INFO - 'SHELL': '/bin/bash', 09:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:13 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:13 INFO - 'USER': 'cltbld', 09:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:13 INFO - 'XPC_FLAGS': '0x0', 09:26:13 INFO - 'XPC_SERVICE_NAME': '0', 09:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:15 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:15 INFO - warning: no files found matching '*.txt' 09:26:15 INFO - no previously-included directories found matching 'build' 09:26:15 INFO - no previously-included directories found matching 'dist' 09:26:15 INFO - no previously-included directories found matching '.git*' 09:26:15 INFO - Installing collected packages: jsonschema, functools32 09:26:15 INFO - Running setup.py install for functools32 09:26:15 INFO - warning: no files found matching '*.txt' 09:26:15 INFO - no previously-included directories found matching 'build' 09:26:15 INFO - no previously-included directories found matching 'dist' 09:26:15 INFO - no previously-included directories found matching '.git*' 09:26:16 INFO - Successfully installed jsonschema functools32 09:26:16 INFO - Cleaning up... 09:26:16 INFO - Return code: 0 09:26:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:16 INFO - 'HOME': '/Users/cltbld', 09:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:16 INFO - 'LOGNAME': 'cltbld', 09:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:16 INFO - 'MOZ_NO_REMOTE': '1', 09:26:16 INFO - 'NO_EM_RESTART': '1', 09:26:16 INFO - 'PAGER': '/bin/cat', 09:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:16 INFO - 'PWD': '/builds/slave/test', 09:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:16 INFO - 'SHELL': '/bin/bash', 09:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:16 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:16 INFO - 'USER': 'cltbld', 09:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:16 INFO - 'XPC_FLAGS': '0x0', 09:26:16 INFO - 'XPC_SERVICE_NAME': '0', 09:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:16 INFO - Cleaning up... 09:26:16 INFO - Return code: 0 09:26:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:16 INFO - 'HOME': '/Users/cltbld', 09:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:16 INFO - 'LOGNAME': 'cltbld', 09:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:16 INFO - 'MOZ_NO_REMOTE': '1', 09:26:16 INFO - 'NO_EM_RESTART': '1', 09:26:16 INFO - 'PAGER': '/bin/cat', 09:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:16 INFO - 'PWD': '/builds/slave/test', 09:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:16 INFO - 'SHELL': '/bin/bash', 09:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:16 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:16 INFO - 'USER': 'cltbld', 09:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:16 INFO - 'XPC_FLAGS': '0x0', 09:26:16 INFO - 'XPC_SERVICE_NAME': '0', 09:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:17 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - Downloading docopt-0.6.1.tar.gz 09:26:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:18 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:18 INFO - Running setup.py install for blobuploader 09:26:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:19 INFO - Running setup.py install for requests 09:26:19 INFO - Running setup.py install for docopt 09:26:19 INFO - Successfully installed blobuploader requests docopt 09:26:19 INFO - Cleaning up... 09:26:19 INFO - Return code: 0 09:26:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26: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')]} 09:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26: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')]} 09:26:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:19 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 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:19 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 09:26:19 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 09:26:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:19 INFO - 'HOME': '/Users/cltbld', 09:26:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:19 INFO - 'LOGNAME': 'cltbld', 09:26:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:19 INFO - 'MOZ_NO_REMOTE': '1', 09:26:19 INFO - 'NO_EM_RESTART': '1', 09:26:19 INFO - 'PAGER': '/bin/cat', 09:26:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:19 INFO - 'PWD': '/builds/slave/test', 09:26:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:19 INFO - 'SHELL': '/bin/bash', 09:26:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:19 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:19 INFO - 'USER': 'cltbld', 09:26:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:19 INFO - 'XPC_FLAGS': '0x0', 09:26:19 INFO - 'XPC_SERVICE_NAME': '0', 09:26:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-WjEv4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-3mpsFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Pvg5gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-3Rva6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ajr5dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-yjOryH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-zHkcIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-C5R64D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ZMamUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:20 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-PkvK_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-L9UrBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-XKOHGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-9ZIjXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-L0lwEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ywuwjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-2jiA_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:21 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-EneWkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:21 INFO - Running setup.py install for manifestparser 09:26:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:21 INFO - Running setup.py install for mozcrash 09:26:22 INFO - Running setup.py install for mozdebug 09:26:22 INFO - Running setup.py install for mozdevice 09:26:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Running setup.py install for mozfile 09:26:22 INFO - Running setup.py install for mozhttpd 09:26:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Running setup.py install for mozinfo 09:26:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Running setup.py install for mozInstall 09:26:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:26:22 INFO - Running setup.py install for mozleak 09:26:23 INFO - Running setup.py install for mozlog 09:26:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Running setup.py install for moznetwork 09:26:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Running setup.py install for mozprocess 09:26:23 INFO - Running setup.py install for mozprofile 09:26:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Running setup.py install for mozrunner 09:26:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:26:23 INFO - Running setup.py install for mozscreenshot 09:26:24 INFO - Running setup.py install for moztest 09:26:24 INFO - Running setup.py install for mozversion 09:26:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:26:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:26:24 INFO - Cleaning up... 09:26:24 INFO - Return code: 0 09:26:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:26:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:24 INFO - 'HOME': '/Users/cltbld', 09:26:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:24 INFO - 'LOGNAME': 'cltbld', 09:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:24 INFO - 'MOZ_NO_REMOTE': '1', 09:26:24 INFO - 'NO_EM_RESTART': '1', 09:26:24 INFO - 'PAGER': '/bin/cat', 09:26:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:24 INFO - 'PWD': '/builds/slave/test', 09:26:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:24 INFO - 'SHELL': '/bin/bash', 09:26:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:24 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:24 INFO - 'USER': 'cltbld', 09:26:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:24 INFO - 'XPC_FLAGS': '0x0', 09:26:24 INFO - 'XPC_SERVICE_NAME': '0', 09:26:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:24 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gqLKS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:24 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)) 09:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:24 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BmlWBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:24 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)) 09:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:24 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-lgPUsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:24 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)) 09:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:24 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-26_y8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-HrTSXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-yhkOtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4mrnza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-wycNqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-apMYOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:25 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)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Y4IMHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:25 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)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-GzlNok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:25 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)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ctnoO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:25 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)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-dWBXWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:25 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)) 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:25 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-wBDes3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:26 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-fx6pdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:26 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-QdZGpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:26 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-T4ykRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:26:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:26: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 09:26: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 09:26: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 09:26: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 09:26:27 INFO - Downloading blessings-1.6.tar.gz 09:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:26:27 INFO - Installing collected packages: blessings 09:26:27 INFO - Running setup.py install for blessings 09:26:28 INFO - Successfully installed blessings 09:26:28 INFO - Cleaning up... 09:26:28 INFO - Return code: 0 09:26:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:26:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:28 INFO - Reading from file tmpfile_stdout 09:26:28 INFO - Current package versions: 09:26:28 INFO - blessings == 1.6 09:26:28 INFO - blobuploader == 1.2.4 09:26:28 INFO - docopt == 0.6.1 09:26:28 INFO - functools32 == 3.2.3-2 09:26:28 INFO - jsonschema == 2.5.1 09:26:28 INFO - manifestparser == 1.1 09:26:28 INFO - mozInstall == 1.12 09:26:28 INFO - mozcrash == 1.0 09:26:28 INFO - mozdebug == 0.1 09:26:28 INFO - mozdevice == 0.49 09:26:28 INFO - mozfile == 1.2 09:26:28 INFO - mozhttpd == 0.7 09:26:28 INFO - mozinfo == 0.9 09:26:28 INFO - mozleak == 0.1 09:26:28 INFO - mozlog == 3.4 09:26:28 INFO - moznetwork == 0.27 09:26:28 INFO - mozprocess == 0.25 09:26:28 INFO - mozprofile == 0.28 09:26:28 INFO - mozrunner == 6.13 09:26:28 INFO - mozscreenshot == 0.1 09:26:28 INFO - mozsystemmonitor == 0.3 09:26:28 INFO - moztest == 0.8 09:26:28 INFO - mozversion == 1.4 09:26:28 INFO - psutil == 3.1.1 09:26:28 INFO - requests == 1.2.3 09:26:28 INFO - wsgiref == 0.1.2 09:26:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:26:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:28 INFO - 'HOME': '/Users/cltbld', 09:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:28 INFO - 'LOGNAME': 'cltbld', 09:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:28 INFO - 'MOZ_NO_REMOTE': '1', 09:26:28 INFO - 'NO_EM_RESTART': '1', 09:26:28 INFO - 'PAGER': '/bin/cat', 09:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:28 INFO - 'PWD': '/builds/slave/test', 09:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:28 INFO - 'SHELL': '/bin/bash', 09:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:28 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:28 INFO - 'USER': 'cltbld', 09:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:28 INFO - 'XPC_FLAGS': '0x0', 09:26:28 INFO - 'XPC_SERVICE_NAME': '0', 09:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 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)) 09:26:28 INFO - Cleaning up... 09:26:28 INFO - Return code: 0 09:26:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:28 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 0x10cf92b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf95768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb02d25690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, '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.Cy90ASw9gg/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.GFbionTVE9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 09:26:28 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 09:26:28 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 09:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:28 INFO - 'HOME': '/Users/cltbld', 09:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:28 INFO - 'LOGNAME': 'cltbld', 09:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:28 INFO - 'MOZ_NO_REMOTE': '1', 09:26:28 INFO - 'NO_EM_RESTART': '1', 09:26:28 INFO - 'PAGER': '/bin/cat', 09:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:28 INFO - 'PWD': '/builds/slave/test', 09:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:28 INFO - 'SHELL': '/bin/bash', 09:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:28 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:28 INFO - 'USER': 'cltbld', 09:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:28 INFO - 'XPC_FLAGS': '0x0', 09:26:28 INFO - 'XPC_SERVICE_NAME': '0', 09:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:26:28 INFO - Cleaning up... 09:26:28 INFO - Return code: 0 09:26:28 INFO - Running post-action listener: _resource_record_post_action 09:26:28 INFO - Running post-action listener: _start_resource_monitoring 09:26:28 INFO - Starting resource monitoring. 09:26:29 INFO - [mozharness: 2017-05-16 16:26:29.003732Z] Finished create-virtualenv step (success) 09:26:29 INFO - [mozharness: 2017-05-16 16:26:29.004719Z] Running install step. 09:26:29 INFO - Running pre-action listener: _resource_record_pre_action 09:26:29 INFO - Running main action method: install 09:26:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:29 INFO - Reading from file tmpfile_stdout 09:26:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:26:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:26:29 INFO - Reading from file tmpfile_stdout 09:26:29 INFO - Output received: 09:26:29 INFO - Usage: mozinstall [options] installer 09:26:29 INFO - Options: 09:26:29 INFO - -h, --help show this help message and exit 09:26:29 INFO - -d DEST, --destination=DEST 09:26:29 INFO - Directory to install application into. [default: 09:26:29 INFO - "/builds/slave/test"] 09:26:29 INFO - --app=APP Application being installed. [default: firefox] 09:26:29 INFO - mkdir: /builds/slave/test/build/application 09:26:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:26:44 INFO - Reading from file tmpfile_stdout 09:26:44 INFO - Output received: 09:26:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:26:44 INFO - Running post-action listener: _resource_record_post_action 09:26:44 INFO - [mozharness: 2017-05-16 16:26:44.244400Z] Finished install step (success) 09:26:44 INFO - [mozharness: 2017-05-16 16:26:44.244532Z] Running run-tests step. 09:26:44 INFO - Running pre-action listener: _resource_record_pre_action 09:26:44 INFO - Running main action method: run_tests 09:26:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:26:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:26:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:26:44 INFO - 2017-05-16 09:26:44.259 screenresolution[1633:8185] starting screenresolution argv=screenresolution get 09:26:44 INFO - 2017-05-16 09:26:44.261 screenresolution[1633:8185] Display 0: 1600x1200x32@60 09:26:44 INFO - 2017-05-16 09:26:44.267 screenresolution[1634:8187] starting screenresolution argv=screenresolution list 09:26:44 INFO - Available Modes on Display 0 09:26:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:26:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:26:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:26:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:26:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:26:44 INFO - Intel Iris: 09:26:44 INFO - Chipset Model: Intel Iris 09:26:44 INFO - Type: GPU 09:26:44 INFO - Bus: Built-In 09:26:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:26:44 INFO - Vendor: Intel (0x8086) 09:26:44 INFO - Device ID: 0x0a2e 09:26:44 INFO - Revision ID: 0x0009 09:26:44 INFO - Displays: 09:26:44 INFO - Display: 09:26:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:26:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:26:44 INFO - Display Serial Number: 1600x1200 60 09:26:44 INFO - Main Display: Yes 09:26:44 INFO - Mirror: Off 09:26:44 INFO - Online: Yes 09:26:44 INFO - Rotation: Supported 09:26:44 INFO - Return code: 0 09:26:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:26:44 INFO - Python 2.7.3 09:26:44 INFO - Return code: 0 09:26:44 INFO - grabbing minidump binary from tooltool 09:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:44 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd70d30>, '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 0x10cc97db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd65be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:26:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:26:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:26:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:26:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:26:44 INFO - Return code: 0 09:26:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:26:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:26:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:26:44 INFO - ENV: RUST_BACKTRACE is now 1 09:26:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:26:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:26:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 09:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --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 09:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cy90ASw9gg/Render', 09:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:44 INFO - 'HOME': '/Users/cltbld', 09:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:44 INFO - 'LOGNAME': 'cltbld', 09:26:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:44 INFO - 'MOZ_NO_REMOTE': '1', 09:26:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:44 INFO - 'NO_EM_RESTART': '1', 09:26:44 INFO - 'PAGER': '/bin/cat', 09:26:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:44 INFO - 'PWD': '/builds/slave/test', 09:26:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:44 INFO - 'RUST_BACKTRACE': '1', 09:26:44 INFO - 'SHELL': '/bin/bash', 09:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GFbionTVE9/Listeners', 09:26:44 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 09:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:44 INFO - 'USER': 'cltbld', 09:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:44 INFO - 'XPC_FLAGS': '0x0', 09:26:44 INFO - 'XPC_SERVICE_NAME': '0', 09:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 09:26:44 INFO - mozversion application_buildid: 20170516074024 09:26:44 INFO - mozversion application_changeset: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 09:26:44 INFO - mozversion application_display_name: Firefox 09:26:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:26:44 INFO - mozversion application_name: Firefox 09:26:44 INFO - mozversion application_remotingname: firefox 09:26:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:26:44 INFO - mozversion application_vendor: Mozilla 09:26:44 INFO - mozversion application_version: 54.0 09:26:44 INFO - mozversion platform_buildid: 20170516074024 09:26:44 INFO - mozversion platform_changeset: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 09:26:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:26:44 INFO - mozversion platform_version: 54.0 09:26:44 INFO - using testdate: 1494952004 09:26:44 INFO - actual date: 1494952004 09:26:44 INFO - starting webserver on 'localhost:49225' 09:26:44 INFO - SUITE-START | Running 2 tests 09:26:44 INFO - TEST-START | dromaeo_css 09:26:44 INFO - operating with platform_type : mac_ 09:26:44 INFO - Initialising browser for dromaeo_css test... 09:26:45 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmprTt0Ga/profile http://localhost:49225/getInfo.html) 09:26:47 INFO - PROCESS | 1644 | 2017-05-16 09:26:47.939 plugin-container[1649:8384] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 09:26:47 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:47 INFO - PROCESS | 1644 | 2017-05-16 09:26:47.940 plugin-container[1649:8384] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 09:26:47 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:47 INFO - PROCESS | 1644 | 2017-05-16 09:26:47.940 plugin-container[1649:8384] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:47 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 09:26:47 INFO - PROCESS | 1644 | colorDepth:24 09:26:47 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 09:26:47 INFO - PROCESS | 1644 | __metrics 09:26:48 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:26:48 INFO - TEST-INFO | 1644: exit 0 09:26:48 INFO - Browser initialized. 09:26:48 INFO - Running cycle 1/1 for dromaeo_css test... 09:26:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmprTt0Ga/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:50 INFO - PROCESS | 1650 | 2017-05-16 09:26:50.973 plugin-container[1651:8513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:26:50 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:50 INFO - PROCESS | 1650 | 2017-05-16 09:26:50.974 plugin-container[1651:8513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:26:50 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:50 INFO - PROCESS | 1650 | 2017-05-16 09:26:50.974 plugin-container[1651:8513] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:00 INFO - PROCESS | 1650 | RSS: Main: 174104576 09:27:00 INFO - PROCESS | 1650 | 09:30:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 09:30:05 INFO - PROCESS | 1650 | RSS: Main: 195481600 09:30:05 INFO - PROCESS | 1650 | 09:33:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 09:33:07 INFO - PROCESS | 1650 | RSS: Main: 206159872 09:33:07 INFO - PROCESS | 1650 | 09:36:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 09:36:10 INFO - PROCESS | 1650 | RSS: Main: 207585280 09:36:10 INFO - PROCESS | 1650 | 09:39:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 09:39:12 INFO - PROCESS | 1650 | RSS: Main: 210694144 09:39:12 INFO - PROCESS | 1650 | 09:42:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 09:42:16 INFO - PROCESS | 1650 | RSS: Main: 213643264 09:42:16 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 09:45:19 INFO - PROCESS | 1650 | RSS: Main: 211677184 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | __start_tp_report 09:45:19 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:45:19 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:45:19 INFO - PROCESS | 1650 | |i|pagename|runs| 09:45:19 INFO - PROCESS | 1650 | |0;dojo.html;3552.8934131976016;3557.9466308005385;3570.553680789901;3585.13956650404;3593.478945552895;6197.690115494224;6205.503559715224;6213.378662133784;6253.499720022399;6258.655773932438;4960.528749768775;6171.4445699887;6200.503959683226;6218.844528886769;6256.343083976185;5481.588880833933;5508.4491550845;5547.500724934755;5551.278333816598;5626.381098079208;4916.975415122919;5252.290940722992;5564.33228012637;5597;5622.240997465351;5011.696958790704;5134.1015322318635;5462.153366228221;5600.747966341504;5602.1036634138345;5026.271190677331;5193.013327467788;5568.49883510484;5636.239107720447;5652.06740887753;5239.633225674204;5454.91817622736;5597.104463285896;5610.102383618642;5616.410276920906;6021.5182785377065;6179.412955769195;6214.036824292242;6231.189945307081;6254.968725156391;6430.742770289183;6451.096846441502;6463.612183269019;6584.407403333675;6584.5061620378665;6686.531942764009;6701.765438209639;6705.396514313735;6713.56361836479;6731.32686731323;6244.063390491463;6248;6265.71804268809;6267.6866156691985;6294.464970477515;6432.839179020469;6594.142761440982;6707.429868461138;6711.597304161766;6761.256261811197;6537.5750576212395;6550.410463058348;6560.507961902877;6567.8358041048405;6578.243501997236;6569.737210511526;6630.071789949411;6670.666466676646;6682.164729408824;6686;6289.559730818799;6343.238811342669;6431.389018043278;6460.418562329413;6593.340665933464;5632.183333416631;5662.773489060474;6403.5271887465215;6422.678866056678;6583.670816459158;5297.099493086184;5298.735063246822;5357.973210134002;5381.219723140122;5397.892042159135;4726.3146843708155;4783.210770222874;4791.808327666854;4797.424309082932;4811.566958973709;6108.402780883668;6207.968960155261;6209.161763162006;6212.223472065992;6222.097795819581;6524.9673751630935;6583.111279977237;6611.371919667624;6722.899156512656;6743.5953842769595;6221.906671399933;6221.9377806222265;6233.2208473940755;6268.404501572343;6270.059491076375;4838.588719958772;5849.181114643953;5917.644941303535;5918.496927761103;5925.081612350093;6496.22045354551;6571.178602674666;6597.2742998271115;6634.104395906684;6746.392824645805;6534.771283005072;6539.574927629689;6603.636799975855;6617.073609694743;6646.567973081735;5562.887422515594;6732;6756.493263005393;6785.084013658093;6806.523543351917;6335.1130841683125;6360.840978975378;6495.317991611002;6497.772577959749;6518.087467754613;6655.434288085567;6655.935050391914;6659.500537459613;6665.933340666531;6666.466682665278;6348.858192549559;6424;6507.089007539043;6532.150820393318;6533.150690410216;6249.593776404519;6262.718177682106;6363.981762917911;6467.159269294962;6512.251091124582;6302.621842689453;6311.24265088174;6312.3687631238345;6365.427111559982;6373.2989371168205;6482.57863238888;6492.675366231764;6493.383128602775;6550.082988381726;6566.409023187936;6146.446819786241;6243.125962365181;6256.468200203034;6281.905771413524;6316.747330106744 09:45:19 INFO - PROCESS | 1650 | |1;ext.html;773.5397438524079;812.508432398399;822.6051495282265;841.2933136165622;977.8533220017001;38367.80816095919;39287.21425571487;40740.796296018554;40757;40818.18363632725;39471;39656.8102956911;39805.40291895617;39816.40275395864;39958;8254.380921430902;8372.706955256568;11139.832902506469;11383.203175777699;11575.421228938561;11161.330320180816;11204.887951120467;11260.662180134568;11262.943685281603;11271.549138034468;11227.494762735696;11270.323780573151;11321.886781132165;11328.773424531504;11336.886631133706;11251.60619378322;11282.323060616389;11292.548298068108;11299.70053443848;11314.660560183167;11067.280626759235;11067.280626759315;11075;11132.276402033842;11138.387388693618;34741;34768.65231347679;34970.65029349699;35021.824890875636;35047.824760876036;3832.2143960488033;3899.707521935866;3902.902427439308;3916.177602703407;3921.9215615687813;20136.39590812278;26536;27004.594931076048;27278.318042048908;27602.447951041067;3192.505161699917;3197.5843140391835;3245.545623612696;3245.545623612696;3263.559419478364;349507;351376;404302;410639;445467;43232.91917702083;43508.3473747894;43624.56375436269;43771;43832.78083609562;45104.3234351485;45290.54709452929;45450.77274613672;45587;45618.63144105682;13968.161910285415;14163.079399838978;14186.85813141876;14242.928785356007;14271.286435678174;12318.322492262832;12573.1198816082;12575.685607859878;12580.496780128693;12602.684932876751;10067.949660251652;11534.307941523535;11902.821457678141;11909.821352679717;12013;4914.140025495595;5730.398308177665;5757.568182386254;5859.531237501075;5869.853253668693;1080.475969154959;1110.3171549497163;1122.5902545570807;1146.6101525481376;1167.486306025346;24498.142565010137;26460.206193814218;26600.733992660214;26744.59883101755;26804.59793103105;1221.8045112781913;1237.5049980008068;1265.379963817741;1294.7345794112373;1296.6304603188235;10644.77585077726;13820.861791382158;13989.020768546105;14063;14063.874890008774;376653;379495;382432;384172;384834;55546.88906221853;55776.60558486071;56122.71938640362;56178.43821561814;56260;1141.4007645985757;1463.4000059975358;1502.346479281538;1506.7739839024234;1511.7883496310526;25349.366265843502;25979.480410392065;26263.080792172183;26375.868120659274;26403.60394594121;44041.77979110212;44557;44563;44600.77699611611;44659;393262;393481;393568;393645;394279;30318.545221822133;30937.8453107733;31049.534256985713;31124.377512450075;31124.533132002583;39793.80103099466;41363.965900852716;42134;43342.34986475269;43402.348964766185;28742.42515149727;28944.855275723487;29039.69321380585;29435.96974105896;29530.261743456587;28801.56797647995;29218.41563168682;29248.976285829893;29652.851735741184;29831.701682982894;33928.66071339255;34008.979730608124;34493.310133797684;34624.480632791034;34732.47901281432;27838.3040423991;28519.71661275206;29356.853215733783;29991.100266992024;30319.242018949702;23473;23652.172173973828;23761.64357534673;23766;24031.639525406783 09:45:19 INFO - PROCESS | 1650 | |2;jquery.html;362.2664105186996;408.666936446796;410.48279168248035;421.33639517759525;450.6349856616141;20518.89740551295;20809.687854682194;22006.669899951514;22080.668789968124;22154.88922555387;21729.456763580878;22512.88743556284;22891;23032.76967230327;23127.421814454647;6647.567908085983;6930.237673855871;7004.859902801941;7014.929850701478;7022.36798688118;5191.5327620514145;6270.184875966118;6784.423324017464;6793.082933803923;6815.148106486689;5648.830535083934;5936.910946335787;6599.373059559334;6880.862382752342;6884.449244060488;5876.823695289148;6879.449644028466;6884.242733299333;6898.758543450955;6981.616011119412;6729.125213722234;7221.638918054076;7546.622668866535;7720.7683769487;7730;26363.472730545473;26368.8681556591;26812.59781103285;26944.32639184036;27229.183124506297;2575.639410482534;2970.7029297070335;2975.211568934234;3071;3089.602452571031;14317.64205894861;15645.843541564553;16924.830751692447;16958.23687934046;17368.78418510692;1827.7936561869235;2113;2117.915283388663;2162.816160626349;2176.455886028493;122959;150703.98592028208;168071.79820504462;169039;174380;20295.797042029684;21498.785012149678;21574.460638484165;21638.783612163676;21814.127434902428;32997;33443.49834752481;33668.66331336655;33678.32643347119;33959.4906076409;10447.425391603383;10675.733106672395;10682.839757403644;10964.56141754321;10991.175661825395;8964.417312874642;8982.730518084469;9021.188093071654;9034.503102329434;9194.26445884341;9653.758656033655;9713.174380177765;9784.119429251401;9788.119069283799;9791.020897910152;3567.3245066200348;4297.8495752648505;4344.543822898613;4354.433923589913;4355.455568053993;1063.984040239397;1090.9744839850516;1095.2771171027084;1106.6292791914798;1128.7121783945206;20611.38165855027;21191;22720.318390448312;22832.771672283397;22904.969276382602;933.1228645073745;1111.2388014210292;1138.362516990488;1145.1125377832248;1150.1488898215262;8816.691415800417;9545.809083818285;11281.32312061279;11467.31196128235;11845.5854045108;608211;614653;630172;725607;775614;156948.21525892525;160258;162117;162245;163270.18364908337;1243.9937800311122;1244.3591550351575;1296.1834044551872;1322.444573279225;1338.0299283019733;19900.402987910387;20210.39368818938;20443.693344600142;20597.176112955312;20621;54070.459295406545;56576.86846263134;64396;64735.67632161809;64976;85006.7248991278;87117.56441217754;87651.68522472041;88887.55556222178;89017.10982890088;8613.655453782028;11762.529498819951;11790.410479476164;12104.273743575413;12149.514019439124;12041.759164816829;12409.255444673348;13076.019298552837;13472.326383680973;13512.324383780575;9548.710924025168;11417.086633069168;11596.130290228099;11653.00949419309;11682.182247242612;8541.974963004479;10877.075448586642;10941;11065.557377704801;11195.60815371458;11740.471678774305;11811.05511559056;11967.401629918644;12116.697082572653;12211.87788122143;9414.905850941403;11759.294442333145;11765.647030589096;12009.699757506132;12129.75740485203;5786.218860453785;9038.728838134866;11683.240589361665;11832.82250766222;11920.344381059125 09:45:19 INFO - PROCESS | 1650 | |3;mootools.html;1583.6515966487368;1634.2972521815623;1649.6040950171962;1652.512508809901;1657.453040496636;11092.500837462314;11494.942525287383;11768.941155294222;11804.9965752911;11859.644210673694;11650.941745291282;11755.823662645067;11875;11899.107566932471;11911.821322680145;2441.6093425051995;2474.084588702183;2474.1711526638596;2478.5538603051446;2488.2659615413404;2405.603075492542;2412.49337639096;2445.1808644104244;2448.7061552613623;2458.1150785717127;2406.831521793475;2428.3079322393123;2434.45224824414;2441.438469152096;2462.3597864555168;2386.2602593196166;2440.511897620474;2456.238566044514;2462.852228866265;2464.8151388645833;2396.8801559921935;2427.4295540547955;2428.4414584645456;2437.5856104462105;2437.8171637127284;11336.979671829493;11588.826167607493;11592.710182245428;11592.884071159266;11606.071514278921;5822.737976791054;5961.970190149022;5990.490808281258;6005.369436209179;6037.7584896604085;3564.1624218308675;3624.3657359962167;3653.8355773990234;3670.4127339625534;3671.761335513183;1242.7017515796235;1251.230493246656;1293.7024484368649;1306.3925274747194;1307.7776777947772;13695.520656776966;13967.301634918213;15129.848701512954;15758.290876910567;15869.920650396674;4684.2271025281125;4752.572268495783;4781.42622885256;4797.088553174885;4816.662833601615;12285.324307163013;12288.324142172087;12300.384980750927;12331.198472099284;12345.074119441078;4172.812223449952;5846.947549441056;5863.794767183128;5879.559033072536;5907.261592300963;5200.817971370984;5365.946340536622;5385.973070134624;5388.326459192601;5403.13549832025;3818.7135964802196;3927.3716205407;3983.5618082010956;3999.8400063997115;4040.757554546678;1022.99488502557;1026.1124127629546;1029.4183786160795;1031.4842578710645;1039.9064084232455;648.7275344355382;835.7116794705817;995.3530205366425;1007.6725064354114;1030.9587316810075;5049.469805670426;6717.596944183365;6854.10896583441;6971.233164351917;7058.25888281723;1090.8418279349607;1101.531848964187;1104.4919337104861;1105.6682995101598;1107.7064577886867;4393.648508119407;4514.367988481616;4517.119161763425;4632.62938964882;4636.721796692209;13407.865921340857;13616.251106189056;13727.519536816164;13735.96980226487;13966.301684915714;13610.221407192306;13847.16916984964;13945.442182312796;13980.44078236859;13981.510647127301;10510;10555.419451930218;10743;10764.677059688222;10787.460626968621;6793.184817821893;6855.451563874978;6881.518293719391;7038.718451261892;7108.431325494052;12283.263004219776;12404.875951240372;12739.745205096031;12751.362431878555;12786.68033299175;11560.248583842023;12776.552820651232;12838.55065072718;13093.2847916617;13132.474701011852;9473.621055157717;9579.568919398784;9711.5144242789;9728.708138755848;9737.367071140354;10551.261411701107;10565.9471702641;10585.153187744809;10615.787684246117;10639.680809575726;9039.45763254207;9161.725148245563;9186.40288381253;9306;9353.859692104488;7518.060242469692;8777.118358111233;8922.86615700778;8959.776005599912;9096.408733432305;7898;8004.319632831041;8113.797155071171;8114.431989760897;8117.553534555656;5932.317783454785;5943.732532036155;6002.219711437485;6134.754609815736;6162.322144564184;5991.700414979146;6000.8799824004145;6056.485198758155;6089.2997305310455;6237.708613011401 09:45:19 INFO - PROCESS | 1650 | |4;prototype.html;362.1742427265834;390.94135879618074;391.95688474267826;405.72410760682743;407.6840448652295;4825.469198388175;5326.307580014597;5482.753276102575;5516.282883225175;5629.565964761492;4119.423280740699;4856.7571621418965;5297.007190937118;5353.785848566053;5457.372402173752;3727.366347720887;3919.745216560929;4475.462944446671;4672.462666793328;4746.958807712682;3657.3782456982244;4030.919381612366;4685.742284174369;4706.29405589161;4786.941615105503;3789.7683252942766;3899.2396482685886;3999.6800255979597;4554.749488778132;4591.839285625004;4108.28105081611;4410.051838854662;4457.665675074382;4630.097131059445;4794.280857871295;3419.128122328815;3848.1726428817938;4183.895402614929;4275.358696195564;4328.394024836525;4205.936910946339;4836.322914791932;5072.746362681851;5279.287296215;5289.233061206142;4445.8221671133115;4908.840110351434;5343.332083489564;5448.46646309464;5561.08142690771;4774.856754297377;5854.268216472941;5919.289685237756;5937.524998000193;5978.8505287367725;4127.195196936598;4548.931766023512;4851.248056551241;5088.720120393377;5112.463191364927;5711.600187986843;5846.327672317653;5902.763889444417;6173.845653858644;6230.688465576703;4520.282292728742;5207.927322879519;5220.295260139908;5240.554552862973;5343;4458.621017213508;5210.426853046162;5331.093714068617;5372.415137534436;5440.219047761847;4769.976150119227;5090.81466018789;5230.032443997872;5501.14732216507;5505.531515145432;4764.761761911891;5048.671836330626;5110.156824124052;5803.694168812068;5857.577528965604;1706.695960453378;1834.9082545872652;1844.8193156379928;1848.676481615712;1850.4171186076344;1367.3300082959477;1451.7442412313274;1451.84948661405;1484.1095342794238;1503.1657430126165;4509.458864936163;5430.321209848769;5440.823670531819;5469.507744302952;5493.423190565013;4769.972761576386;5349.839504814784;5353.812616558402;5668.914966275509;5940.910886336708;4419.491758447755;4627.668078983127;4912.38595175603;5211;5418.891622167614;2722.754952054325;2883.6251287332934;3088;3113.486274764684;3133.0914034929615;4954.9954545908895;5128.512791284822;5577.888442231214;5938.61399009063;6183.876322473526;5038.748062596856;5542.528885044736;5774.575050626671;5847.06446968483;6045;5364.329458817648;5410.675359478444;6212.72042758077;6227.782027629102;6282.905756413749;5182.481751824787;5585.413531579125;5803.65178089316;5942.58401911862;6058.939410605837;5160.638755287094;5798.507126894261;5858.075089689833;5976.551758618034;6003.489703375088;5364.678119312854;5673.404292549223;5765.596408251382;5796.62321949072;6173.84565385869;3973.463582416337;4148.771817550064;4239.915201696011;4415.028693687384;4427.959429533992;5151.330327057459;5711.715313874483;5974.9103763444455;5987;6073.66379396532;4449.17690227309;4897.436794768505;5225.693576605849;5557.055300598989;5797.333306669619;4880.560749532559;4948.356713627206;5598.944010559842;5648.830535083954;5699.601027928005;4468.944874033425;5477.726113694379;5626.803061892814;5633.774649014157;5662.85842853932;5537.833864984057;5570.407087428667;5797.91303130445;5808.5353171747;5826.970865145647;5080.390353157644;5645.379008309164;5657.43425657431;5665.065264231521;5695.060315047971 09:45:19 INFO - PROCESS | 1650 | |5;yui.html;757.6476938223725;811.1442428238206;823.9659227669274;834.6125758230622;867.5402036920434;12490.750184996296;16840.578985525368;16910.408135715254;17167.742483862738;17234.396796112138;12723.745525089516;14557.344919478623;15902;15906.363745450168;15919.363225470966;959.6065613098614;971.7376308396729;1010.5705075342987;1017.6641708236265;1020.3214862116683;1004.3572113847143;1005.5826831864767;1006.4011912911806;1007.169085504458;1007.2244371833684;1044.6813721814844;1060.697701155171;1063;1065.7282392989778;1077.154433769491;1057.9100776434013;1059.2585190366735;1059.8622179116742;1062.198040479437;1062.3678911047914;779.4465929190281;785.9096203936506;787.2953706432768;787.3189690917316;788.9329407000412;16108.355665773355;16611.169441527876;17044.062576558284;17074.743878841826;17348.95906245629;14615.70768584633;14740.484083057148;14874.181919994397;14920.552383428514;15039.022463539835;11587.30476171424;11945.104117191246;11988.580399686056;12079.637610871689;12193.17102434633;3781.640744129303;3800.5059342285326;3813;4027.8791636250958;4037.0916543777707;105692;114739.2789108164;117971;118133.40933295364;121078;10844.72888177786;11180.944095279472;11404.372759498141;11441.885581144248;11570.652880413601;23923.401914952265;24087;24105;24107.638385424234;24138.034478621008;8024.358051355877;8240.464369815943;8252.752417427488;8410.621522031523;8422.831543369099;4555.954440455619;4571.1086338164405;4615.976920115378;4629.606483448944;4764.76116209788;3565.821708914596;3610.7472476926364;3620.637936206358;3651.598324184338;3660.249648821985;660.4386271669139;665.3778716899715;668.7224801707237;677.38019711963;677.3937326093119;699.4823830365594;700.8995876473989;717.4511498703496;718.6334969165658;744.9664765085585;9938.45338506389;10167.796644067224;10196.643117490852;10425.545670195956;10515.263931524873;670.8715880432189;671.4192223726442;702.9015937768717;705.1573369823114;722.5917356693451;5044.873878153076;5133.6919784813035;5184.8963020739375;5198.116320225571;5213.556847667991;81390.20254310223;97371;104764;104949;106334;92569;95030;95905;97848.51075744575;98152.50923745336;85185;85398;86063.56968215245;87961;88880.5555972216;9856.556454959687;9941.950290248502;9953.651622193189;9958.601655933971;9999.450030248407;53994.730026349614;54276.72861635825;55108.44891551033;55547.72226138844;56556.8688626217;45345;49831;65695.67152164209;69492.65253673699;69759.30240697529;10751.6236931707;10861.185411094357;11051.944740276569;11331.830022549833;11445.141614379121;12991.960643148337;13158.671033224246;13188.868111318763;13855.376508057363;13864.376103075587;10012.449315287438;11678.941605292259;11955.462004210005;12250.571230006906;12334.444949977453;11825.172237943605;12197.207181533173;12206.389680515762;12236.694082648006;12365.443555040225;11398.772024559297;11822.349770762346;12081.697957551132;12316.445759940645;12482.625521234377;8573.91426085756;9013.18881300686;9215.44707317563;9252.935912370163;9356.953215234153;9853.408795472295;10265.538050787583;10315.638952636622;10332.328398654365;10347.896521034694 09:45:19 INFO - PROCESS | 1650 | __end_tp_report 09:45:19 INFO - PROCESS | 1650 | __start_cc_report 09:45:19 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,388 09:45:19 INFO - PROCESS | 1650 | __end_cc_report 09:45:19 INFO - PROCESS | 1650 | __startTimestamp1494953119714__endTimestamp 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:45:19 INFO - PROCESS | 1650 | Number of tests: 6 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:6081.80 Median:6281.91 stddev:689.19 (11.0%) stddev-sans-first:662.13 09:45:19 INFO - PROCESS | 1650 | Values: 3552.9 3557.9 3570.6 3585.1 3593.5 6197.7 6205.5 6213.4 6253.5 6258.7 4960.5 6171.4 6200.5 6218.8 6256.3 5481.6 5508.4 5547.5 5551.3 5626.4 4917.0 5252.3 5564.3 5597.0 5622.2 5011.7 5134.1 5462.2 5600.7 5602.1 5026.3 5193.0 5568.5 5636.2 5652.1 5239.6 5454.9 5597.1 5610.1 5616.4 6021.5 6179.4 6214.0 6231.2 6255.0 6430.7 6451.1 6463.6 6584.4 6584.5 6686.5 6701.8 6705.4 6713.6 6731.3 6244.1 6248.0 6265.7 6267.7 6294.5 6432.8 6594.1 6707.4 6711.6 6761.3 6537.6 6550.4 6560.5 6567.8 6578.2 6569.7 6630.1 6670.7 6682.2 6686.0 6289.6 6343.2 6431.4 6460.4 6593.3 5632.2 5662.8 6403.5 6422.7 6583.7 5297.1 5298.7 5358.0 5381.2 5397.9 4726.3 4783.2 4791.8 4797.4 4811.6 6108.4 6208.0 6209.2 6212.2 6222.1 6525.0 6583.1 6611.4 6722.9 6743.6 6221.9 6221.9 6233.2 6268.4 6270.1 4838.6 5849.2 5917.6 5918.5 5925.1 6496.2 6571.2 6597.3 6634.1 6746.4 6534.8 6539.6 6603.6 6617.1 6646.6 5562.9 6732.0 6756.5 6785.1 6806.5 6335.1 6360.8 6495.3 6497.8 6518.1 6655.4 6655.9 6659.5 6665.9 6666.5 6348.9 6424.0 6507.1 6532.2 6533.2 6249.6 6262.7 6364.0 6467.2 6512.3 6302.6 6311.2 6312.4 6365.4 6373.3 6482.6 6492.7 6493.4 6550.1 6566.4 6146.4 6243.1 6256.5 6281.9 6316.7 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:52561.51 Median:29911.40 stddev:103050.89 (344.5%) stddev-sans-first:103266.62 09:45:19 INFO - PROCESS | 1650 | Values: 773.5 812.5 822.6 841.3 977.9 38367.8 39287.2 40740.8 40757.0 40818.2 39471.0 39656.8 39805.4 39816.4 39958.0 8254.4 8372.7 11139.8 11383.2 11575.4 11161.3 11204.9 11260.7 11262.9 11271.5 11227.5 11270.3 11321.9 11328.8 11336.9 11251.6 11282.3 11292.5 11299.7 11314.7 11067.3 11067.3 11075.0 11132.3 11138.4 34741.0 34768.7 34970.7 35021.8 35047.8 3832.2 3899.7 3902.9 3916.2 3921.9 20136.4 26536.0 27004.6 27278.3 27602.4 3192.5 3197.6 3245.5 3245.5 3263.6 349507.0 351376.0 404302.0 410639.0 445467.0 43232.9 43508.3 43624.6 43771.0 43832.8 45104.3 45290.5 45450.8 45587.0 45618.6 13968.2 14163.1 14186.9 14242.9 14271.3 12318.3 12573.1 12575.7 12580.5 12602.7 10067.9 11534.3 11902.8 11909.8 12013.0 4914.1 5730.4 5757.6 5859.5 5869.9 1080.5 1110.3 1122.6 1146.6 1167.5 24498.1 26460.2 26600.7 26744.6 26804.6 1221.8 1237.5 1265.4 1294.7 1296.6 10644.8 13820.9 13989.0 14063.0 14063.9 376653.0 379495.0 382432.0 384172.0 384834.0 55546.9 55776.6 56122.7 56178.4 56260.0 1141.4 1463.4 1502.3 1506.8 1511.8 25349.4 25979.5 26263.1 26375.9 26403.6 44041.8 44557.0 44563.0 44600.8 44659.0 393262.0 393481.0 393568.0 393645.0 394279.0 30318.5 30937.8 31049.5 31124.4 31124.5 39793.8 41364.0 42134.0 43342.3 43402.3 28742.4 28944.9 29039.7 29436.0 29530.3 28801.6 29218.4 29249.0 29652.9 29831.7 33928.7 34009.0 34493.3 34624.5 34732.5 27838.3 28519.7 29356.9 29991.1 30319.2 23473.0 23652.2 23761.6 23766.0 24031.6 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:41337.36 Median:22776.55 stddev:113529.45 (498.4%) stddev-sans-first:113806.24 09:45:19 INFO - PROCESS | 1650 | Values: 362.3 408.7 410.5 421.3 450.6 20518.9 20809.7 22006.7 22080.7 22154.9 21729.5 22512.9 22891.0 23032.8 23127.4 6647.6 6930.2 7004.9 7014.9 7022.4 5191.5 6270.2 6784.4 6793.1 6815.1 5648.8 5936.9 6599.4 6880.9 6884.4 5876.8 6879.4 6884.2 6898.8 6981.6 6729.1 7221.6 7546.6 7720.8 7730.0 26363.5 26368.9 26812.6 26944.3 27229.2 2575.6 2970.7 2975.2 3071.0 3089.6 14317.6 15645.8 16924.8 16958.2 17368.8 1827.8 2113.0 2117.9 2162.8 2176.5 122959.0 150704.0 168071.8 169039.0 174380.0 20295.8 21498.8 21574.5 21638.8 21814.1 32997.0 33443.5 33668.7 33678.3 33959.5 10447.4 10675.7 10682.8 10964.6 10991.2 8964.4 8982.7 9021.2 9034.5 9194.3 9653.8 9713.2 9784.1 9788.1 9791.0 3567.3 4297.8 4344.5 4354.4 4355.5 1064.0 1091.0 1095.3 1106.6 1128.7 20611.4 21191.0 22720.3 22832.8 22905.0 933.1 1111.2 1138.4 1145.1 1150.1 8816.7 9545.8 11281.3 11467.3 11845.6 608211.0 614653.0 630172.0 725607.0 775614.0 156948.2 160258.0 162117.0 162245.0 163270.2 1244.0 1244.4 1296.2 1322.4 1338.0 19900.4 20210.4 20443.7 20597.2 20621.0 54070.5 56576.9 64396.0 64735.7 64976.0 85006.7 87117.6 87651.7 88887.6 89017.1 8613.7 11762.5 11790.4 12104.3 12149.5 12041.8 12409.3 13076.0 13472.3 13512.3 9548.7 11417.1 11596.1 11653.0 11682.2 8542.0 10877.1 10941.0 11065.6 11195.6 11740.5 11811.1 11967.4 12116.7 12211.9 9414.9 11759.3 11765.6 12009.7 12129.8 5786.2 9038.7 11683.2 11832.8 11920.3 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:6924.99 Median:2443.40 stddev:4320.54 (176.8%) stddev-sans-first:4314.02 09:45:19 INFO - PROCESS | 1650 | Values: 1583.7 1634.3 1649.6 1652.5 1657.5 11092.5 11494.9 11768.9 11805.0 11859.6 11650.9 11755.8 11875.0 11899.1 11911.8 2441.6 2474.1 2474.2 2478.6 2488.3 2405.6 2412.5 2445.2 2448.7 2458.1 2406.8 2428.3 2434.5 2441.4 2462.4 2386.3 2440.5 2456.2 2462.9 2464.8 2396.9 2427.4 2428.4 2437.6 2437.8 11337.0 11588.8 11592.7 11592.9 11606.1 5822.7 5962.0 5990.5 6005.4 6037.8 3564.2 3624.4 3653.8 3670.4 3671.8 1242.7 1251.2 1293.7 1306.4 1307.8 13695.5 13967.3 15129.8 15758.3 15869.9 4684.2 4752.6 4781.4 4797.1 4816.7 12285.3 12288.3 12300.4 12331.2 12345.1 4172.8 5846.9 5863.8 5879.6 5907.3 5200.8 5365.9 5386.0 5388.3 5403.1 3818.7 3927.4 3983.6 3999.8 4040.8 1023.0 1026.1 1029.4 1031.5 1039.9 648.7 835.7 995.4 1007.7 1031.0 5049.5 6717.6 6854.1 6971.2 7058.3 1090.8 1101.5 1104.5 1105.7 1107.7 4393.6 4514.4 4517.1 4632.6 4636.7 13407.9 13616.3 13727.5 13736.0 13966.3 13610.2 13847.2 13945.4 13980.4 13981.5 10510.0 10555.4 10743.0 10764.7 10787.5 6793.2 6855.5 6881.5 7038.7 7108.4 12283.3 12404.9 12739.7 12751.4 12786.7 11560.2 12776.6 12838.6 13093.3 13132.5 9473.6 9579.6 9711.5 9728.7 9737.4 10551.3 10565.9 10585.2 10615.8 10639.7 9039.5 9161.7 9186.4 9306.0 9353.9 7518.1 8777.1 8922.9 8959.8 9096.4 7898.0 8004.3 8113.8 8114.4 8117.6 5932.3 5943.7 6002.2 6134.8 6162.3 5991.7 6000.9 6056.5 6089.3 6237.7 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4799.65 Median:5215.65 stddev:1306.77 (25.1%) stddev-sans-first:1267.28 09:45:19 INFO - PROCESS | 1650 | Values: 362.2 390.9 392.0 405.7 407.7 4825.5 5326.3 5482.8 5516.3 5629.6 4119.4 4856.8 5297.0 5353.8 5457.4 3727.4 3919.7 4475.5 4672.5 4747.0 3657.4 4030.9 4685.7 4706.3 4786.9 3789.8 3899.2 3999.7 4554.7 4591.8 4108.3 4410.1 4457.7 4630.1 4794.3 3419.1 3848.2 4183.9 4275.4 4328.4 4205.9 4836.3 5072.7 5279.3 5289.2 4445.8 4908.8 5343.3 5448.5 5561.1 4774.9 5854.3 5919.3 5937.5 5978.9 4127.2 4548.9 4851.2 5088.7 5112.5 5711.6 5846.3 5902.8 6173.8 6230.7 4520.3 5207.9 5220.3 5240.6 5343.0 4458.6 5210.4 5331.1 5372.4 5440.2 4770.0 5090.8 5230.0 5501.1 5505.5 4764.8 5048.7 5110.2 5803.7 5857.6 1706.7 1834.9 1844.8 1848.7 1850.4 1367.3 1451.7 1451.8 1484.1 1503.2 4509.5 5430.3 5440.8 5469.5 5493.4 4770.0 5349.8 5353.8 5668.9 5940.9 4419.5 4627.7 4912.4 5211.0 5418.9 2722.8 2883.6 3088.0 3113.5 3133.1 4955.0 5128.5 5577.9 5938.6 6183.9 5038.7 5542.5 5774.6 5847.1 6045.0 5364.3 5410.7 6212.7 6227.8 6282.9 5182.5 5585.4 5803.7 5942.6 6058.9 5160.6 5798.5 5858.1 5976.6 6003.5 5364.7 5673.4 5765.6 5796.6 6173.8 3973.5 4148.8 4239.9 4415.0 4428.0 5151.3 5711.7 5974.9 5987.0 6073.7 4449.2 4897.4 5225.7 5557.1 5797.3 4880.6 4948.4 5598.9 5648.8 5699.6 4468.9 5477.7 5626.8 5633.8 5662.9 5537.8 5570.4 5797.9 5808.5 5827.0 5080.4 5645.4 5657.4 5665.1 5695.1 09:45:19 INFO - PROCESS | 1650 | 09:45:19 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:20987.12 Median:24096.00 stddev:30809.76 (127.9%) stddev-sans-first:30858.75 09:45:19 INFO - PROCESS | 1650 | Values: 757.6 811.1 824.0 834.6 867.5 12490.8 16840.6 16910.4 17167.7 17234.4 12723.7 14557.3 15902.0 15906.4 15919.4 959.6 971.7 1010.6 1017.7 1020.3 1004.4 1005.6 1006.4 1007.2 1007.2 1044.7 1060.7 1063.0 1065.7 1077.2 1057.9 1059.3 1059.9 1062.2 1062.4 779.4 785.9 787.3 787.3 788.9 16108.4 16611.2 17044.1 17074.7 17349.0 14615.7 14740.5 14874.2 14920.6 15039.0 11587.3 11945.1 11988.6 12079.6 12193.2 3781.6 3800.5 3813.0 4027.9 4037.1 105692.0 114739.3 117971.0 118133.4 121078.0 10844.7 11180.9 11404.4 11441.9 11570.7 23923.4 24087.0 24105.0 24107.6 24138.0 8024.4 8240.5 8252.8 8410.6 8422.8 4556.0 4571.1 4616.0 4629.6 4764.8 3565.8 3610.7 3620.6 3651.6 3660.2 660.4 665.4 668.7 677.4 677.4 699.5 700.9 717.5 718.6 745.0 9938.5 10167.8 10196.6 10425.5 10515.3 670.9 671.4 702.9 705.2 722.6 5044.9 5133.7 5184.9 5198.1 5213.6 81390.2 97371.0 104764.0 104949.0 106334.0 92569.0 95030.0 95905.0 97848.5 98152.5 85185.0 85398.0 86063.6 87961.0 88880.6 9856.6 9942.0 9953.7 9958.6 9999.5 53994.7 54276.7 55108.4 55547.7 56556.9 45345.0 49831.0 65695.7 69492.7 69759.3 10751.6 10861.2 11051.9 11331.8 11445.1 12992.0 13158.7 13188.9 13855.4 13864.4 10012.4 11678.9 11955.5 12250.6 12334.4 11825.2 12197.2 12206.4 12236.7 12365.4 11398.8 11822.3 12081.7 12316.4 12482.6 8573.9 9013.2 9215.4 9252.9 9357.0 9853.4 10265.5 10315.6 10332.3 10347.9 09:45:19 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:45:19 INFO - PROCESS | 1650 | 09:45:20 INFO - TEST-INFO | 1650: exit 0 09:45:20 INFO - TEST-OK | dromaeo_css | took 1115419ms 09:45:20 INFO - TEST-START | kraken 09:45:20 INFO - operating with platform_type : mac_ 09:45:20 INFO - Initialising browser for kraken test... 09:45:20 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpAhtuqH/profile http://localhost:49225/getInfo.html) 09:45:22 INFO - PROCESS | 1661 | 2017-05-16 09:45:22.829 plugin-container[1663:11027] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:45:22 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:22 INFO - PROCESS | 1661 | 2017-05-16 09:45:22.829 plugin-container[1663:11027] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4223, name = 'com.apple.CFPasteboardClient' 09:45:22 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:22 INFO - PROCESS | 1661 | 2017-05-16 09:45:22.829 plugin-container[1663:11027] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:45:22 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 09:45:22 INFO - PROCESS | 1661 | colorDepth:24 09:45:22 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 09:45:22 INFO - PROCESS | 1661 | __metrics 09:45:22 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:45:23 INFO - TEST-INFO | 1661: exit 0 09:45:23 INFO - Browser initialized. 09:45:23 INFO - Running cycle 1/1 for kraken test... 09:45:23 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpAhtuqH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:26 INFO - PROCESS | 1664 | 2017-05-16 09:45:26.201 plugin-container[1665:11156] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x823f, name = 'com.apple.tsm.portname' 09:45:26 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:26 INFO - PROCESS | 1664 | 2017-05-16 09:45:26.201 plugin-container[1665:11156] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 09:45:26 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:45:26 INFO - PROCESS | 1664 | 2017-05-16 09:45:26.201 plugin-container[1665:11156] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:45:35 INFO - PROCESS | 1664 | RSS: Main: 174071808 09:45:35 INFO - PROCESS | 1664 | 09:45:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 09:45:40 INFO - PROCESS | 1664 | RSS: Main: 187711488 09:45:40 INFO - PROCESS | 1664 | 09:45:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 09:45:43 INFO - PROCESS | 1664 | RSS: Main: 195657728 09:45:43 INFO - PROCESS | 1664 | 09:45:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 09:45:47 INFO - PROCESS | 1664 | RSS: Main: 199401472 09:45:47 INFO - PROCESS | 1664 | 09:45:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 09:45:49 INFO - PROCESS | 1664 | RSS: Main: 199704576 09:45:49 INFO - PROCESS | 1664 | 09:45:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:45:52 INFO - PROCESS | 1664 | RSS: Main: 201134080 09:45:52 INFO - PROCESS | 1664 | 09:45:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 09:45:55 INFO - PROCESS | 1664 | RSS: Main: 204963840 09:45:55 INFO - PROCESS | 1664 | 09:45:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 09:45:59 INFO - PROCESS | 1664 | RSS: Main: 204455936 09:45:59 INFO - PROCESS | 1664 | 09:46:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 09:46:03 INFO - PROCESS | 1664 | RSS: Main: 204918784 09:46:03 INFO - PROCESS | 1664 | 09:46:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:46:05 INFO - PROCESS | 1664 | RSS: Main: 204611584 09:46:05 INFO - PROCESS | 1664 | 09:46:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:46:08 INFO - PROCESS | 1664 | RSS: Main: 204689408 09:46:08 INFO - PROCESS | 1664 | 09:46:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:46:10 INFO - PROCESS | 1664 | RSS: Main: 204656640 09:46:10 INFO - PROCESS | 1664 | 09:46:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:46:14 INFO - PROCESS | 1664 | RSS: Main: 205058048 09:46:14 INFO - PROCESS | 1664 | 09:46:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:46:17 INFO - PROCESS | 1664 | RSS: Main: 205017088 09:46:17 INFO - PROCESS | 1664 | 09:46:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 09:46:19 INFO - PROCESS | 1664 | RSS: Main: 207855616 09:46:19 INFO - PROCESS | 1664 | 09:46:19 INFO - PROCESS | 1664 | __start_tp_report 09:46:19 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 09:46:19 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 09:46:19 INFO - PROCESS | 1664 | |i|pagename|runs| 09:46:19 INFO - PROCESS | 1664 | |0;ai-astar;107;117;133;175;163;118;119;118;121;143 09:46:19 INFO - PROCESS | 1664 | |1;audio-beat-detection;140;108;109;142;111;108;113;112;127;113 09:46:20 INFO - PROCESS | 1664 | |2;audio-dft;274;255;194;195;196;196;194;195;197;195 09:46:20 INFO - PROCESS | 1664 | |3;audio-fft;91;85;50;76;74;80;78;110;67;86 09:46:20 INFO - PROCESS | 1664 | |4;audio-oscillator;88;69;85;72;72;69;80;71;72;83 09:46:20 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;91;103;111;89;103;90;91;94;114;91 09:46:20 INFO - PROCESS | 1664 | |6;imaging-darkroom;97;97;98;97;95;97;124;153;138;97 09:46:20 INFO - PROCESS | 1664 | |7;imaging-desaturate;90;112;143;114;91;91;105;147;112;89 09:46:20 INFO - PROCESS | 1664 | |8;json-parse-financial;67;64;65;67;67;68;67;67;70;67 09:46:20 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;57;63;63;64;62;57;56;56;57;58 09:46:20 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;81;84;83;86;86;86;83;83;86;87 09:46:20 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;136;135;137;146;153;152;135;141;194;152 09:46:20 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;168;167;163;163;164;165;163;165;163;164 09:46:20 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;64;65;65;65;61;78;87;87;65;64 09:46:20 INFO - PROCESS | 1664 | __end_tp_report 09:46:20 INFO - PROCESS | 1664 | __start_cc_report 09:46:20 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,987 09:46:20 INFO - PROCESS | 1664 | __end_cc_report 09:46:20 INFO - PROCESS | 1664 | __startTimestamp1494953179995__endTimestamp 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | ------- Summary: start ------- 09:46:20 INFO - PROCESS | 1664 | Number of tests: 14 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:131.40 Median:127.00 stddev:22.26 (17.5%) stddev-sans-first:21.79 09:46:20 INFO - PROCESS | 1664 | Values: 107.0 117.0 133.0 175.0 163.0 118.0 119.0 118.0 121.0 143.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:113.00 stddev:13.15 (11.6%) stddev-sans-first:11.36 09:46:20 INFO - PROCESS | 1664 | Values: 140.0 108.0 109.0 142.0 111.0 108.0 113.0 112.0 127.0 113.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:209.10 Median:196.00 stddev:29.55 (15.1%) stddev-sans-first:19.94 09:46:20 INFO - PROCESS | 1664 | Values: 274.0 255.0 194.0 195.0 196.0 196.0 194.0 195.0 197.0 195.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:79.70 Median:79.00 stddev:15.66 (19.8%) stddev-sans-first:16.06 09:46:20 INFO - PROCESS | 1664 | Values: 91.0 85.0 50.0 76.0 74.0 80.0 78.0 110.0 67.0 86.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:76.00 stddev:7.16 (9.4%) stddev-sans-first:6.16 09:46:20 INFO - PROCESS | 1664 | Values: 88.0 69.0 85.0 72.0 72.0 69.0 80.0 71.0 72.0 83.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:97.70 Median:90.50 stddev:9.32 (10.3%) stddev-sans-first:9.57 09:46:20 INFO - PROCESS | 1664 | Values: 91.0 103.0 111.0 89.0 103.0 90.0 91.0 94.0 114.0 91.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:97.00 stddev:21.18 (21.8%) stddev-sans-first:21.99 09:46:20 INFO - PROCESS | 1664 | Values: 97.0 97.0 98.0 97.0 95.0 97.0 124.0 153.0 138.0 97.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:109.40 Median:118.00 stddev:21.26 (18.0%) stddev-sans-first:21.35 09:46:20 INFO - PROCESS | 1664 | Values: 90.0 112.0 143.0 114.0 91.0 91.0 105.0 147.0 112.0 89.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:67.00 stddev:1.60 (2.4%) stddev-sans-first:1.69 09:46:20 INFO - PROCESS | 1664 | Values: 67.0 64.0 65.0 67.0 67.0 68.0 67.0 67.0 70.0 67.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:60.00 stddev:3.27 (5.4%) stddev-sans-first:3.36 09:46:20 INFO - PROCESS | 1664 | Values: 57.0 63.0 63.0 64.0 62.0 57.0 56.0 56.0 57.0 58.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:86.00 stddev:1.96 (2.3%) stddev-sans-first:1.62 09:46:20 INFO - PROCESS | 1664 | Values: 81.0 84.0 83.0 86.0 86.0 86.0 83.0 83.0 86.0 87.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:149.00 stddev:17.73 (11.9%) stddev-sans-first:18.26 09:46:20 INFO - PROCESS | 1664 | Values: 136.0 135.0 137.0 146.0 153.0 152.0 135.0 141.0 194.0 152.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:164.50 stddev:1.78 (1.1%) stddev-sans-first:1.36 09:46:20 INFO - PROCESS | 1664 | Values: 168.0 167.0 163.0 163.0 164.0 165.0 163.0 165.0 163.0 164.0 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:65.00 stddev:9.97 (15.3%) stddev-sans-first:10.33 09:46:20 INFO - PROCESS | 1664 | Values: 64.0 65.0 65.0 65.0 61.0 78.0 87.0 87.0 65.0 64.0 09:46:20 INFO - PROCESS | 1664 | -------- Summary: end -------- 09:46:20 INFO - PROCESS | 1664 | 09:46:20 INFO - TEST-INFO | 1664: exit 0 09:46:20 INFO - TEST-OK | kraken | took 60554ms 09:46:20 INFO - SUITE-END | took 1175s 09:46:21 INFO - Completed test suite (00:19:36) 09:46:21 INFO - javascript benchmark 09:46:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7872.7258952443635, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3552.8934131976016, 3557.9466308005385, 3570.553680789901, 3585.13956650404, 3593.478945552895, 6197.690115494224, 6205.503559715224, 6213.378662133784, 6253.499720022399, 6258.655773932438, 4960.528749768775, 6171.4445699887, 6200.503959683226, 6218.844528886769, 6256.343083976185, 5481.588880833933, 5508.4491550845, 5547.500724934755, 5551.278333816598, 5626.381098079208, 4916.975415122919, 5252.290940722992, 5564.33228012637, 5597.0, 5622.240997465351, 5011.696958790704, 5134.1015322318635, 5462.153366228221, 5600.747966341504, 5602.1036634138345, 5026.271190677331, 5193.013327467788, 5568.49883510484, 5636.239107720447, 5652.06740887753, 5239.633225674204, 5454.91817622736, 5597.104463285896, 5610.102383618642, 5616.410276920906, 6021.5182785377065, 6179.412955769195, 6214.036824292242, 6231.189945307081, 6254.968725156391, 6430.742770289183, 6451.096846441502, 6463.612183269019, 6584.407403333675, 6584.5061620378665, 6686.531942764009, 6701.765438209639, 6705.396514313735, 6713.56361836479, 6731.32686731323, 6244.063390491463, 6248.0, 6265.71804268809, 6267.6866156691985, 6294.464970477515, 6432.839179020469, 6594.142761440982, 6707.429868461138, 6711.597304161766, 6761.256261811197, 6537.5750576212395, 6550.410463058348, 6560.507961902877, 6567.8358041048405, 6578.243501997236, 6569.737210511526, 6630.071789949411, 6670.666466676646, 6682.164729408824, 6686.0, 6289.559730818799, 6343.238811342669, 6431.389018043278, 6460.418562329413, 6593.340665933464, 5632.183333416631, 5662.773489060474, 6403.5271887465215, 6422.678866056678, 6583.670816459158, 5297.099493086184, 5298.735063246822, 5357.973210134002, 5381.219723140122, 5397.892042159135, 4726.3146843708155, 4783.210770222874, 4791.808327666854, 4797.424309082932, 4811.566958973709, 6108.402780883668, 6207.968960155261, 6209.161763162006, 6212.223472065992, 6222.097795819581, 6524.9673751630935, 6583.111279977237, 6611.371919667624, 6722.899156512656, 6743.5953842769595, 6221.906671399933, 6221.9377806222265, 6233.2208473940755, 6268.404501572343, 6270.059491076375, 4838.588719958772, 5849.181114643953, 5917.644941303535, 5918.496927761103, 5925.081612350093, 6496.22045354551, 6571.178602674666, 6597.2742998271115, 6634.104395906684, 6746.392824645805, 6534.771283005072, 6539.574927629689, 6603.636799975855, 6617.073609694743, 6646.567973081735, 5562.887422515594, 6732.0, 6756.493263005393, 6785.084013658093, 6806.523543351917, 6335.1130841683125, 6360.840978975378, 6495.317991611002, 6497.772577959749, 6518.087467754613, 6655.434288085567, 6655.935050391914, 6659.500537459613, 6665.933340666531, 6666.466682665278, 6348.858192549559, 6424.0, 6507.089007539043, 6532.150820393318, 6533.150690410216, 6249.593776404519, 6262.718177682106, 6363.981762917911, 6467.159269294962, 6512.251091124582, 6302.621842689453, 6311.24265088174, 6312.3687631238345, 6365.427111559982, 6373.2989371168205, 6482.57863238888, 6492.675366231764, 6493.383128602775, 6550.082988381726, 6566.409023187936, 6146.446819786241, 6243.125962365181, 6256.468200203034, 6281.905771413524, 6316.747330106744], "value": 6038.872849556641, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.5397438524079, 812.508432398399, 822.6051495282265, 841.2933136165622, 977.8533220017001, 38367.80816095919, 39287.21425571487, 40740.796296018554, 40757.0, 40818.18363632725, 39471.0, 39656.8102956911, 39805.40291895617, 39816.40275395864, 39958.0, 8254.380921430902, 8372.706955256568, 11139.832902506469, 11383.203175777699, 11575.421228938561, 11161.330320180816, 11204.887951120467, 11260.662180134568, 11262.943685281603, 11271.549138034468, 11227.494762735696, 11270.323780573151, 11321.886781132165, 11328.773424531504, 11336.886631133706, 11251.60619378322, 11282.323060616389, 11292.548298068108, 11299.70053443848, 11314.660560183167, 11067.280626759235, 11067.280626759315, 11075.0, 11132.276402033842, 11138.387388693618, 34741.0, 34768.65231347679, 34970.65029349699, 35021.824890875636, 35047.824760876036, 3832.2143960488033, 3899.707521935866, 3902.902427439308, 3916.177602703407, 3921.9215615687813, 20136.39590812278, 26536.0, 27004.594931076048, 27278.318042048908, 27602.447951041067, 3192.505161699917, 3197.5843140391835, 3245.545623612696, 3245.545623612696, 3263.559419478364, 349507.0, 351376.0, 404302.0, 410639.0, 445467.0, 43232.91917702083, 43508.3473747894, 43624.56375436269, 43771.0, 43832.78083609562, 45104.3234351485, 45290.54709452929, 45450.77274613672, 45587.0, 45618.63144105682, 13968.161910285415, 14163.079399838978, 14186.85813141876, 14242.928785356007, 14271.286435678174, 12318.322492262832, 12573.1198816082, 12575.685607859878, 12580.496780128693, 12602.684932876751, 10067.949660251652, 11534.307941523535, 11902.821457678141, 11909.821352679717, 12013.0, 4914.140025495595, 5730.398308177665, 5757.568182386254, 5859.531237501075, 5869.853253668693, 1080.475969154959, 1110.3171549497163, 1122.5902545570807, 1146.6101525481376, 1167.486306025346, 24498.142565010137, 26460.206193814218, 26600.733992660214, 26744.59883101755, 26804.59793103105, 1221.8045112781913, 1237.5049980008068, 1265.379963817741, 1294.7345794112373, 1296.6304603188235, 10644.77585077726, 13820.861791382158, 13989.020768546105, 14063.0, 14063.874890008774, 376653.0, 379495.0, 382432.0, 384172.0, 384834.0, 55546.88906221853, 55776.60558486071, 56122.71938640362, 56178.43821561814, 56260.0, 1141.4007645985757, 1463.4000059975358, 1502.346479281538, 1506.7739839024234, 1511.7883496310526, 25349.366265843502, 25979.480410392065, 26263.080792172183, 26375.868120659274, 26403.60394594121, 44041.77979110212, 44557.0, 44563.0, 44600.77699611611, 44659.0, 393262.0, 393481.0, 393568.0, 393645.0, 394279.0, 30318.545221822133, 30937.8453107733, 31049.534256985713, 31124.377512450075, 31124.533132002583, 39793.80103099466, 41363.965900852716, 42134.0, 43342.34986475269, 43402.348964766185, 28742.42515149727, 28944.855275723487, 29039.69321380585, 29435.96974105896, 29530.261743456587, 28801.56797647995, 29218.41563168682, 29248.976285829893, 29652.851735741184, 29831.701682982894, 33928.66071339255, 34008.979730608124, 34493.310133797684, 34624.480632791034, 34732.47901281432, 27838.3040423991, 28519.71661275206, 29356.853215733783, 29991.100266992024, 30319.242018949702, 23473.0, 23652.172173973828, 23761.64357534673, 23766.0, 24031.639525406783], "value": 18479.67201750501, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.2664105186996, 408.666936446796, 410.48279168248035, 421.33639517759525, 450.6349856616141, 20518.89740551295, 20809.687854682194, 22006.669899951514, 22080.668789968124, 22154.88922555387, 21729.456763580878, 22512.88743556284, 22891.0, 23032.76967230327, 23127.421814454647, 6647.567908085983, 6930.237673855871, 7004.859902801941, 7014.929850701478, 7022.36798688118, 5191.5327620514145, 6270.184875966118, 6784.423324017464, 6793.082933803923, 6815.148106486689, 5648.830535083934, 5936.910946335787, 6599.373059559334, 6880.862382752342, 6884.449244060488, 5876.823695289148, 6879.449644028466, 6884.242733299333, 6898.758543450955, 6981.616011119412, 6729.125213722234, 7221.638918054076, 7546.622668866535, 7720.7683769487, 7730.0, 26363.472730545473, 26368.8681556591, 26812.59781103285, 26944.32639184036, 27229.183124506297, 2575.639410482534, 2970.7029297070335, 2975.211568934234, 3071.0, 3089.602452571031, 14317.64205894861, 15645.843541564553, 16924.830751692447, 16958.23687934046, 17368.78418510692, 1827.7936561869235, 2113.0, 2117.915283388663, 2162.816160626349, 2176.455886028493, 122959.0, 150703.98592028208, 168071.79820504462, 169039.0, 174380.0, 20295.797042029684, 21498.785012149678, 21574.460638484165, 21638.783612163676, 21814.127434902428, 32997.0, 33443.49834752481, 33668.66331336655, 33678.32643347119, 33959.4906076409, 10447.425391603383, 10675.733106672395, 10682.839757403644, 10964.56141754321, 10991.175661825395, 8964.417312874642, 8982.730518084469, 9021.188093071654, 9034.503102329434, 9194.26445884341, 9653.758656033655, 9713.174380177765, 9784.119429251401, 9788.119069283799, 9791.020897910152, 3567.3245066200348, 4297.8495752648505, 4344.543822898613, 4354.433923589913, 4355.455568053993, 1063.984040239397, 1090.9744839850516, 1095.2771171027084, 1106.6292791914798, 1128.7121783945206, 20611.38165855027, 21191.0, 22720.318390448312, 22832.771672283397, 22904.969276382602, 933.1228645073745, 1111.2388014210292, 1138.362516990488, 1145.1125377832248, 1150.1488898215262, 8816.691415800417, 9545.809083818285, 11281.32312061279, 11467.31196128235, 11845.5854045108, 608211.0, 614653.0, 630172.0, 725607.0, 775614.0, 156948.21525892525, 160258.0, 162117.0, 162245.0, 163270.18364908337, 1243.9937800311122, 1244.3591550351575, 1296.1834044551872, 1322.444573279225, 1338.0299283019733, 19900.402987910387, 20210.39368818938, 20443.693344600142, 20597.176112955312, 20621.0, 54070.459295406545, 56576.86846263134, 64396.0, 64735.67632161809, 64976.0, 85006.7248991278, 87117.56441217754, 87651.68522472041, 88887.55556222178, 89017.10982890088, 8613.655453782028, 11762.529498819951, 11790.410479476164, 12104.273743575413, 12149.514019439124, 12041.759164816829, 12409.255444673348, 13076.019298552837, 13472.326383680973, 13512.324383780575, 9548.710924025168, 11417.086633069168, 11596.130290228099, 11653.00949419309, 11682.182247242612, 8541.974963004479, 10877.075448586642, 10941.0, 11065.557377704801, 11195.60815371458, 11740.471678774305, 11811.05511559056, 11967.401629918644, 12116.697082572653, 12211.87788122143, 9414.905850941403, 11759.294442333145, 11765.647030589096, 12009.699757506132, 12129.75740485203, 5786.218860453785, 9038.728838134866, 11683.240589361665, 11832.82250766222, 11920.344381059125], "value": 11749.874437518152, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.6515966487368, 1634.2972521815623, 1649.6040950171962, 1652.512508809901, 1657.453040496636, 11092.500837462314, 11494.942525287383, 11768.941155294222, 11804.9965752911, 11859.644210673694, 11650.941745291282, 11755.823662645067, 11875.0, 11899.107566932471, 11911.821322680145, 2441.6093425051995, 2474.084588702183, 2474.1711526638596, 2478.5538603051446, 2488.2659615413404, 2405.603075492542, 2412.49337639096, 2445.1808644104244, 2448.7061552613623, 2458.1150785717127, 2406.831521793475, 2428.3079322393123, 2434.45224824414, 2441.438469152096, 2462.3597864555168, 2386.2602593196166, 2440.511897620474, 2456.238566044514, 2462.852228866265, 2464.8151388645833, 2396.8801559921935, 2427.4295540547955, 2428.4414584645456, 2437.5856104462105, 2437.8171637127284, 11336.979671829493, 11588.826167607493, 11592.710182245428, 11592.884071159266, 11606.071514278921, 5822.737976791054, 5961.970190149022, 5990.490808281258, 6005.369436209179, 6037.7584896604085, 3564.1624218308675, 3624.3657359962167, 3653.8355773990234, 3670.4127339625534, 3671.761335513183, 1242.7017515796235, 1251.230493246656, 1293.7024484368649, 1306.3925274747194, 1307.7776777947772, 13695.520656776966, 13967.301634918213, 15129.848701512954, 15758.290876910567, 15869.920650396674, 4684.2271025281125, 4752.572268495783, 4781.42622885256, 4797.088553174885, 4816.662833601615, 12285.324307163013, 12288.324142172087, 12300.384980750927, 12331.198472099284, 12345.074119441078, 4172.812223449952, 5846.947549441056, 5863.794767183128, 5879.559033072536, 5907.261592300963, 5200.817971370984, 5365.946340536622, 5385.973070134624, 5388.326459192601, 5403.13549832025, 3818.7135964802196, 3927.3716205407, 3983.5618082010956, 3999.8400063997115, 4040.757554546678, 1022.99488502557, 1026.1124127629546, 1029.4183786160795, 1031.4842578710645, 1039.9064084232455, 648.7275344355382, 835.7116794705817, 995.3530205366425, 1007.6725064354114, 1030.9587316810075, 5049.469805670426, 6717.596944183365, 6854.10896583441, 6971.233164351917, 7058.25888281723, 1090.8418279349607, 1101.531848964187, 1104.4919337104861, 1105.6682995101598, 1107.7064577886867, 4393.648508119407, 4514.367988481616, 4517.119161763425, 4632.62938964882, 4636.721796692209, 13407.865921340857, 13616.251106189056, 13727.519536816164, 13735.96980226487, 13966.301684915714, 13610.221407192306, 13847.16916984964, 13945.442182312796, 13980.44078236859, 13981.510647127301, 10510.0, 10555.419451930218, 10743.0, 10764.677059688222, 10787.460626968621, 6793.184817821893, 6855.451563874978, 6881.518293719391, 7038.718451261892, 7108.431325494052, 12283.263004219776, 12404.875951240372, 12739.745205096031, 12751.362431878555, 12786.68033299175, 11560.248583842023, 12776.552820651232, 12838.55065072718, 13093.2847916617, 13132.474701011852, 9473.621055157717, 9579.568919398784, 9711.5144242789, 9728.708138755848, 9737.367071140354, 10551.261411701107, 10565.9471702641, 10585.153187744809, 10615.787684246117, 10639.680809575726, 9039.45763254207, 9161.725148245563, 9186.40288381253, 9306.0, 9353.859692104488, 7518.060242469692, 8777.118358111233, 8922.86615700778, 8959.776005599912, 9096.408733432305, 7898.0, 8004.319632831041, 8113.797155071171, 8114.431989760897, 8117.553534555656, 5932.317783454785, 5943.732532036155, 6002.219711437485, 6134.754609815736, 6162.322144564184, 5991.700414979146, 6000.8799824004145, 6056.485198758155, 6089.2997305310455, 6237.708613011401], "value": 5282.029863578547, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.1742427265834, 390.94135879618074, 391.95688474267826, 405.72410760682743, 407.6840448652295, 4825.469198388175, 5326.307580014597, 5482.753276102575, 5516.282883225175, 5629.565964761492, 4119.423280740699, 4856.7571621418965, 5297.007190937118, 5353.785848566053, 5457.372402173752, 3727.366347720887, 3919.745216560929, 4475.462944446671, 4672.462666793328, 4746.958807712682, 3657.3782456982244, 4030.919381612366, 4685.742284174369, 4706.29405589161, 4786.941615105503, 3789.7683252942766, 3899.2396482685886, 3999.6800255979597, 4554.749488778132, 4591.839285625004, 4108.28105081611, 4410.051838854662, 4457.665675074382, 4630.097131059445, 4794.280857871295, 3419.128122328815, 3848.1726428817938, 4183.895402614929, 4275.358696195564, 4328.394024836525, 4205.936910946339, 4836.322914791932, 5072.746362681851, 5279.287296215, 5289.233061206142, 4445.8221671133115, 4908.840110351434, 5343.332083489564, 5448.46646309464, 5561.08142690771, 4774.856754297377, 5854.268216472941, 5919.289685237756, 5937.524998000193, 5978.8505287367725, 4127.195196936598, 4548.931766023512, 4851.248056551241, 5088.720120393377, 5112.463191364927, 5711.600187986843, 5846.327672317653, 5902.763889444417, 6173.845653858644, 6230.688465576703, 4520.282292728742, 5207.927322879519, 5220.295260139908, 5240.554552862973, 5343.0, 4458.621017213508, 5210.426853046162, 5331.093714068617, 5372.415137534436, 5440.219047761847, 4769.976150119227, 5090.81466018789, 5230.032443997872, 5501.14732216507, 5505.531515145432, 4764.761761911891, 5048.671836330626, 5110.156824124052, 5803.694168812068, 5857.577528965604, 1706.695960453378, 1834.9082545872652, 1844.8193156379928, 1848.676481615712, 1850.4171186076344, 1367.3300082959477, 1451.7442412313274, 1451.84948661405, 1484.1095342794238, 1503.1657430126165, 4509.458864936163, 5430.321209848769, 5440.823670531819, 5469.507744302952, 5493.423190565013, 4769.972761576386, 5349.839504814784, 5353.812616558402, 5668.914966275509, 5940.910886336708, 4419.491758447755, 4627.668078983127, 4912.38595175603, 5211.0, 5418.891622167614, 2722.754952054325, 2883.6251287332934, 3088.0, 3113.486274764684, 3133.0914034929615, 4954.9954545908895, 5128.512791284822, 5577.888442231214, 5938.61399009063, 6183.876322473526, 5038.748062596856, 5542.528885044736, 5774.575050626671, 5847.06446968483, 6045.0, 5364.329458817648, 5410.675359478444, 6212.72042758077, 6227.782027629102, 6282.905756413749, 5182.481751824787, 5585.413531579125, 5803.65178089316, 5942.58401911862, 6058.939410605837, 5160.638755287094, 5798.507126894261, 5858.075089689833, 5976.551758618034, 6003.489703375088, 5364.678119312854, 5673.404292549223, 5765.596408251382, 5796.62321949072, 6173.84565385869, 3973.463582416337, 4148.771817550064, 4239.915201696011, 4415.028693687384, 4427.959429533992, 5151.330327057459, 5711.715313874483, 5974.9103763444455, 5987.0, 6073.66379396532, 4449.17690227309, 4897.436794768505, 5225.693576605849, 5557.055300598989, 5797.333306669619, 4880.560749532559, 4948.356713627206, 5598.944010559842, 5648.830535083954, 5699.601027928005, 4468.944874033425, 5477.726113694379, 5626.803061892814, 5633.774649014157, 5662.85842853932, 5537.833864984057, 5570.407087428667, 5797.91303130445, 5808.5353171747, 5826.970865145647, 5080.390353157644, 5645.379008309164, 5657.43425657431, 5665.065264231521, 5695.060315047971], "value": 4438.985567857898, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.6476938223725, 811.1442428238206, 823.9659227669274, 834.6125758230622, 867.5402036920434, 12490.750184996296, 16840.578985525368, 16910.408135715254, 17167.742483862738, 17234.396796112138, 12723.745525089516, 14557.344919478623, 15902.0, 15906.363745450168, 15919.363225470966, 959.6065613098614, 971.7376308396729, 1010.5705075342987, 1017.6641708236265, 1020.3214862116683, 1004.3572113847143, 1005.5826831864767, 1006.4011912911806, 1007.169085504458, 1007.2244371833684, 1044.6813721814844, 1060.697701155171, 1063.0, 1065.7282392989778, 1077.154433769491, 1057.9100776434013, 1059.2585190366735, 1059.8622179116742, 1062.198040479437, 1062.3678911047914, 779.4465929190281, 785.9096203936506, 787.2953706432768, 787.3189690917316, 788.9329407000412, 16108.355665773355, 16611.169441527876, 17044.062576558284, 17074.743878841826, 17348.95906245629, 14615.70768584633, 14740.484083057148, 14874.181919994397, 14920.552383428514, 15039.022463539835, 11587.30476171424, 11945.104117191246, 11988.580399686056, 12079.637610871689, 12193.17102434633, 3781.640744129303, 3800.5059342285326, 3813.0, 4027.8791636250958, 4037.0916543777707, 105692.0, 114739.2789108164, 117971.0, 118133.40933295364, 121078.0, 10844.72888177786, 11180.944095279472, 11404.372759498141, 11441.885581144248, 11570.652880413601, 23923.401914952265, 24087.0, 24105.0, 24107.638385424234, 24138.034478621008, 8024.358051355877, 8240.464369815943, 8252.752417427488, 8410.621522031523, 8422.831543369099, 4555.954440455619, 4571.1086338164405, 4615.976920115378, 4629.606483448944, 4764.76116209788, 3565.821708914596, 3610.7472476926364, 3620.637936206358, 3651.598324184338, 3660.249648821985, 660.4386271669139, 665.3778716899715, 668.7224801707237, 677.38019711963, 677.3937326093119, 699.4823830365594, 700.8995876473989, 717.4511498703496, 718.6334969165658, 744.9664765085585, 9938.45338506389, 10167.796644067224, 10196.643117490852, 10425.545670195956, 10515.263931524873, 670.8715880432189, 671.4192223726442, 702.9015937768717, 705.1573369823114, 722.5917356693451, 5044.873878153076, 5133.6919784813035, 5184.8963020739375, 5198.116320225571, 5213.556847667991, 81390.20254310223, 97371.0, 104764.0, 104949.0, 106334.0, 92569.0, 95030.0, 95905.0, 97848.51075744575, 98152.50923745336, 85185.0, 85398.0, 86063.56968215245, 87961.0, 88880.5555972216, 9856.556454959687, 9941.950290248502, 9953.651622193189, 9958.601655933971, 9999.450030248407, 53994.730026349614, 54276.72861635825, 55108.44891551033, 55547.72226138844, 56556.8688626217, 45345.0, 49831.0, 65695.67152164209, 69492.65253673699, 69759.30240697529, 10751.6236931707, 10861.185411094357, 11051.944740276569, 11331.830022549833, 11445.141614379121, 12991.960643148337, 13158.671033224246, 13188.868111318763, 13855.376508057363, 13864.376103075587, 10012.449315287438, 11678.941605292259, 11955.462004210005, 12250.571230006906, 12334.444949977453, 11825.172237943605, 12197.207181533173, 12206.389680515762, 12236.694082648006, 12365.443555040225, 11398.772024559297, 11822.349770762346, 12081.697957551132, 12316.445759940645, 12482.625521234377, 8573.91426085756, 9013.18881300686, 9215.44707317563, 9252.935912370163, 9356.953215234153, 9853.408795472295, 10265.538050787583, 10315.638952636622, 10332.328398654365, 10347.896521034694], "value": 7743.64268486932, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 133.0, 175.0, 163.0, 118.0, 119.0, 118.0, 121.0, 143.0], "value": 131.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 109.0, 142.0, 111.0, 108.0, 113.0, 112.0, 127.0, 113.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [274.0, 255.0, 194.0, 195.0, 196.0, 196.0, 194.0, 195.0, 197.0, 195.0], "value": 209.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 50.0, 76.0, 74.0, 80.0, 78.0, 110.0, 67.0, 86.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 85.0, 72.0, 72.0, 69.0, 80.0, 71.0, 72.0, 83.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 103.0, 111.0, 89.0, 103.0, 90.0, 91.0, 94.0, 114.0, 91.0], "value": 97.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 97.0, 95.0, 97.0, 124.0, 153.0, 138.0, 97.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 112.0, 143.0, 114.0, 91.0, 91.0, 105.0, 147.0, 112.0, 89.0], "value": 109.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 67.0, 67.0, 68.0, 67.0, 67.0, 70.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 63.0, 64.0, 62.0, 57.0, 56.0, 56.0, 57.0, 58.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 83.0, 86.0, 86.0, 86.0, 83.0, 83.0, 86.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 137.0, 146.0, 153.0, 152.0, 135.0, 141.0, 194.0, 152.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 163.0, 163.0, 164.0, 165.0, 163.0, 165.0, 163.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 65.0, 61.0, 78.0, 87.0, 87.0, 65.0, 64.0], "value": 70.1, "unit": "score"}]}]} 09:46:21 INFO - Return code: 0 09:46:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:46:21 ERROR - # TBPL SUCCESS # 09:46:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:46:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:46:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:46:21 INFO - 2017-05-16 09:46:21.318 screenresolution[1669:11535] starting screenresolution argv=screenresolution get 09:46:21 INFO - 2017-05-16 09:46:21.320 screenresolution[1669:11535] Display 0: 1600x1200x32@60 09:46:21 INFO - 2017-05-16 09:46:21.326 screenresolution[1670:11538] starting screenresolution argv=screenresolution list 09:46:21 INFO - Available Modes on Display 0 09:46:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:46:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:46:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:46:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:46:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:46:21 INFO - Intel Iris: 09:46:21 INFO - Chipset Model: Intel Iris 09:46:21 INFO - Type: GPU 09:46:21 INFO - Bus: Built-In 09:46:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:46:21 INFO - Vendor: Intel (0x8086) 09:46:21 INFO - Device ID: 0x0a2e 09:46:21 INFO - Revision ID: 0x0009 09:46:21 INFO - Displays: 09:46:21 INFO - Display: 09:46:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:46:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:46:21 INFO - Display Serial Number: 1600x1200 60 09:46:21 INFO - Main Display: Yes 09:46:21 INFO - Mirror: Off 09:46:21 INFO - Online: Yes 09:46:21 INFO - Rotation: Supported 09:46:21 INFO - Return code: 0 09:46:21 INFO - Running post-action listener: _resource_record_post_action 09:46:21 INFO - [mozharness: 2017-05-16 16:46:21.562837Z] Finished run-tests step (success) 09:46:21 INFO - Running post-run listener: _resource_record_post_run 09:46:21 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 34529280; Write bytes: 432598016; Read time: 215; Write time: 1484 09:46:21 INFO - TinderboxPrint: CPU usage
54.4% 09:46:21 INFO - TinderboxPrint: I/O read bytes / time
34,529,280 / 215 09:46:21 INFO - TinderboxPrint: I/O write bytes / time
432,598,016 / 1,484 09:46:21 INFO - TinderboxPrint: CPU idle
2,175.8 (45.6%) 09:46:21 INFO - TinderboxPrint: CPU system
958.3 (20.1%) 09:46:21 INFO - TinderboxPrint: CPU user
1,635.6 (34.3%) 09:46:21 INFO - TinderboxPrint: Swap in / out
153,104,384 / 0 09:46:21 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119575040; Write bytes: 112975872; Read time: 10831; Write time: 247 09:46:21 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 32411648; Write bytes: 308554752; Read time: 194; Write time: 1201 09:46:22 INFO - Running post-run listener: _upload_blobber_files 09:46:22 INFO - Blob upload gear active. 09:46:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:46:22 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 09:46:22 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'] 09:46:22 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 09:46:22 INFO - (blobuploader) - INFO - Open directory for files ... 09:46:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:46:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:46:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:23 INFO - (blobuploader) - INFO - Done attempting. 09:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:24 INFO - (blobuploader) - INFO - Done attempting. 09:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:46:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:25 INFO - (blobuploader) - INFO - Done attempting. 09:46:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:46:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:46:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:46:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:46:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:46:27 INFO - (blobuploader) - INFO - Done attempting. 09:46:27 INFO - (blobuploader) - INFO - Iteration through files over. 09:46:27 INFO - Return code: 0 09:46:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:46:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:46:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b37ed60dfa7c3232ea7020323ad75a10cbc6079f3232d5488c264ad479ecced0de3debe687d01b27c20ab2a8bd5d903a613b944382d68cc681edeb2ba008e4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80e1801d41c2cae3f2a6a0e3cc6ca4f851b789b35279061f7d68af26aab9b80815dbbd762583d84cc91fa99170f64c0d223f957acfebbe79917e08144cfbb2fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f83ba5ee0e45b3b058d3382e08da7e08b3049150c02867a8cc03aa1b3f0d041d705f95fe6e08b13b8bf086bd621d3e7943a1ae697348026249ff513c375e3cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd3770c626cdd53267da1e071d701ffd45f28b32e4d3f71be7151cc23ceef55948a5eb0f6e821fff4b4b810d5359dcb40c8109cd7e7c50716a2251b10a681c71"} 09:46:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:46:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:46:27 INFO - Contents: 09:46:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b37ed60dfa7c3232ea7020323ad75a10cbc6079f3232d5488c264ad479ecced0de3debe687d01b27c20ab2a8bd5d903a613b944382d68cc681edeb2ba008e4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80e1801d41c2cae3f2a6a0e3cc6ca4f851b789b35279061f7d68af26aab9b80815dbbd762583d84cc91fa99170f64c0d223f957acfebbe79917e08144cfbb2fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f83ba5ee0e45b3b058d3382e08da7e08b3049150c02867a8cc03aa1b3f0d041d705f95fe6e08b13b8bf086bd621d3e7943a1ae697348026249ff513c375e3cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd3770c626cdd53267da1e071d701ffd45f28b32e4d3f71be7151cc23ceef55948a5eb0f6e821fff4b4b810d5359dcb40c8109cd7e7c50716a2251b10a681c71"} 09:46:27 INFO - Running post-run listener: copy_logs_to_upload_dir 09:46:27 INFO - Copying logs to upload dir... 09:46:27 INFO - mkdir: /builds/slave/test/build/upload/logs 09:46:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.251844 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-16 09:46:27.864890) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:27.868823) ========= 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.Cy90ASw9gg/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.GFbionTVE9/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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/b37ed60dfa7c3232ea7020323ad75a10cbc6079f3232d5488c264ad479ecced0de3debe687d01b27c20ab2a8bd5d903a613b944382d68cc681edeb2ba008e4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80e1801d41c2cae3f2a6a0e3cc6ca4f851b789b35279061f7d68af26aab9b80815dbbd762583d84cc91fa99170f64c0d223f957acfebbe79917e08144cfbb2fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f83ba5ee0e45b3b058d3382e08da7e08b3049150c02867a8cc03aa1b3f0d041d705f95fe6e08b13b8bf086bd621d3e7943a1ae697348026249ff513c375e3cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd3770c626cdd53267da1e071d701ffd45f28b32e4d3f71be7151cc23ceef55948a5eb0f6e821fff4b4b810d5359dcb40c8109cd7e7c50716a2251b10a681c71"} build_url:https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/T_vkJUf0QaaAUOlQn4NZ2A/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/b37ed60dfa7c3232ea7020323ad75a10cbc6079f3232d5488c264ad479ecced0de3debe687d01b27c20ab2a8bd5d903a613b944382d68cc681edeb2ba008e4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/80e1801d41c2cae3f2a6a0e3cc6ca4f851b789b35279061f7d68af26aab9b80815dbbd762583d84cc91fa99170f64c0d223f957acfebbe79917e08144cfbb2fd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f83ba5ee0e45b3b058d3382e08da7e08b3049150c02867a8cc03aa1b3f0d041d705f95fe6e08b13b8bf086bd621d3e7943a1ae697348026249ff513c375e3cc3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fd3770c626cdd53267da1e071d701ffd45f28b32e4d3f71be7151cc23ceef55948a5eb0f6e821fff4b4b810d5359dcb40c8109cd7e7c50716a2251b10a681c71"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:27.902279) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:27.902617) ========= 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.Cy90ASw9gg/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.GFbionTVE9/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.005218 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 09:46:27.951209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 09:46:27.951531) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 09:46:27.951856) ========= ========= Total master_lag: 0.17 =========