builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0170 starttime: 1495649822.1 results: success (0) buildid: 20170524100420 builduid: a63016b17b0241788f1571fb9c75c496 revision: f7c942b561d57638df5c1c2542dd3d946dc71907 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.104143) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.104561) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.104832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.129327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.129623) ========= 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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-24 11:17:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.6M=0.001s 2017-05-24 11:17:02 (13.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138478 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.291155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.291464) ========= 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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.029280 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:02.341366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 11:17:02.341730) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f7c942b561d57638df5c1c2542dd3d946dc71907 --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 f7c942b561d57638df5c1c2542dd3d946dc71907 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VoohEuhXQr/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.lkwqsUjZ8U/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-24 11:17:02,417 truncating revision to first 12 chars 2017-05-24 11:17:02,417 Setting DEBUG logging. 2017-05-24 11:17:02,417 attempt 1/10 2017-05-24 11:17:02,417 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f7c942b561d5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 11:17:03,505 unpacking tar archive at: mozilla-beta-f7c942b561d5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.377674 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 11:17:03.785595) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:03.785984) ========= script_repo_revision: f7c942b561d57638df5c1c2542dd3d946dc71907 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:03.786441) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:03.786764) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 11:17:03.806232) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-24 11:17:03.806548) ========= /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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:17:03 INFO - MultiFileLogger online at 20170524 11:17:03 in /builds/slave/test 11:17:03 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 11:17:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:17:03 INFO - {'append_to_log': False, 11:17:03 INFO - 'base_work_dir': '/builds/slave/test', 11:17:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:17:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:17:03 INFO - 'branch': 'Mozilla-Beta', 11:17:03 INFO - 'buildbot_json_path': 'buildprops.json', 11:17:03 INFO - 'config_files': ('talos/mac_config.py',), 11:17:03 INFO - 'default_actions': ('clobber', 11:17:03 INFO - 'read-buildbot-config', 11:17:03 INFO - 'download-and-extract', 11:17:03 INFO - 'populate-webroot', 11:17:03 INFO - 'create-virtualenv', 11:17:03 INFO - 'install', 11:17:03 INFO - 'run-tests'), 11:17:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:17:03 INFO - 'download_minidump_stackwalk': True, 11:17:03 INFO - 'download_symbols': 'ondemand', 11:17:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:17:03 INFO - 'tooltool.py': '/tools/tooltool.py', 11:17:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:17:03 INFO - '/tools/misc-python/virtualenv.py')}, 11:17:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:17:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:17:03 INFO - 'gecko_profile': False, 11:17:03 INFO - 'gecko_profile_interval': 0, 11:17:03 INFO - 'installer_path': 'installer.exe', 11:17:03 INFO - 'log_level': 'info', 11:17:03 INFO - 'log_name': 'talos', 11:17:03 INFO - 'log_to_console': True, 11:17:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:17:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:17:03 INFO - 'opt_config_files': (), 11:17:03 INFO - 'pip_index': False, 11:17:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:17:03 INFO - 'cmd': ('bash', 11:17:03 INFO - '-c', 11:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:17:03 INFO - 'enabled': True, 11:17:03 INFO - 'halt_on_failure': False, 11:17:03 INFO - 'name': 'check_screen_resolution'},), 11:17:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:17:03 INFO - 'cmd': ('bash', 11:17:03 INFO - '-c', 11:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:17:03 INFO - 'enabled': True, 11:17:03 INFO - 'halt_on_failure': False, 11:17:03 INFO - 'name': 'check_screen_resolution'},), 11:17:03 INFO - 'run_cmd_checks_enabled': True, 11:17:03 INFO - 'suite': 'dromaeojs-e10s', 11:17:03 INFO - 'system_bits': '32', 11:17:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:17:03 INFO - 'title': 't-yosemite-r7-0170', 11:17:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:17:03 INFO - 'use_talos_json': True, 11:17:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:17:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:17:03 INFO - 'work_dir': 'build'} 11:17:03 INFO - [mozharness: 2017-05-24 18:17:03.999371Z] Running clobber step. 11:17:03 INFO - Running pre-action listener: _resource_record_pre_action 11:17:03 INFO - Running main action method: clobber 11:17:03 INFO - rmtree: /builds/slave/test/build 11:17:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:17:06 INFO - Running post-action listener: _resource_record_post_action 11:17:06 INFO - [mozharness: 2017-05-24 18:17:06.587900Z] Finished clobber step (success) 11:17:06 INFO - [mozharness: 2017-05-24 18:17:06.588056Z] Running read-buildbot-config step. 11:17:06 INFO - Running pre-action listener: _resource_record_pre_action 11:17:06 INFO - Running main action method: read_buildbot_config 11:17:06 INFO - Using buildbot properties: 11:17:06 INFO - { 11:17:06 INFO - "project": "", 11:17:06 INFO - "product": "firefox", 11:17:06 INFO - "script_repo_revision": "production", 11:17:06 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:17:06 INFO - "repository": "", 11:17:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:17:06 INFO - "buildid": "20170524100420", 11:17:06 INFO - "pgo_build": "False", 11:17:06 INFO - "basedir": "/builds/slave/test", 11:17:06 INFO - "buildnumber": 3, 11:17:06 INFO - "slavename": "t-yosemite-r7-0170", 11:17:06 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:17:06 INFO - "platform": "yosemite_r7", 11:17:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:17:06 INFO - "branch": "mozilla-beta", 11:17:06 INFO - "repo_path": "releases/mozilla-beta", 11:17:06 INFO - "revision": "f7c942b561d57638df5c1c2542dd3d946dc71907", 11:17:06 INFO - "stage_platform": "macosx64", 11:17:06 INFO - "builduid": "a63016b17b0241788f1571fb9c75c496", 11:17:06 INFO - "slavebuilddir": "test" 11:17:06 INFO - } 11:17:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:17:06 INFO - Running post-action listener: _resource_record_post_action 11:17:06 INFO - [mozharness: 2017-05-24 18:17:06.591505Z] Finished read-buildbot-config step (success) 11:17:06 INFO - [mozharness: 2017-05-24 18:17:06.591621Z] Running download-and-extract step. 11:17:06 INFO - Running pre-action listener: _resource_record_pre_action 11:17:06 INFO - Running main action method: download_and_extract 11:17:06 INFO - mkdir: /builds/slave/test/build/tests 11:17:06 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')]} 11:17:06 INFO - trying https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:17:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/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 11:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/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 11:17:07 INFO - Downloaded 1287 bytes. 11:17:07 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:17:07 INFO - Using the following test package requirements: 11:17:07 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:17:07 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:17:07 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:17:07 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:17:07 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:17:07 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:17:07 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:17:07 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:17:07 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:17:07 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:17:07 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:17:07 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:17:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:17:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:17:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:17:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:17:09 INFO - Expected file size: 51393554 11:17:09 INFO - Obtained file size: 51393554 11:17:25 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 11:17:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:17:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:17:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:17:27 INFO - Expected file size: 51393554 11:17:27 INFO - Obtained file size: 51393554 11:17:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:17:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:17:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:17:44 INFO - Expected file size: 14404449 11:17:44 INFO - Obtained file size: 14404449 11:17: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')]} 11:17:44 INFO - trying https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:17:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:17:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/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 11:17:47 INFO - Downloaded 59645134 bytes. 11:17:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:17:47 INFO - mkdir: /builds/slave/test/properties 11:17:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:17:47 INFO - Writing to file /builds/slave/test/properties/build_url 11:17:47 INFO - Contents: 11:17:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:17:48 INFO - Running post-action listener: _resource_record_post_action 11:17:48 INFO - Running post-action listener: set_extra_try_arguments 11:17:48 INFO - [mozharness: 2017-05-24 18:17:48.163706Z] Finished download-and-extract step (success) 11:17:48 INFO - [mozharness: 2017-05-24 18:17:48.163848Z] Running populate-webroot step. 11:17:48 INFO - Running pre-action listener: _resource_record_pre_action 11:17:48 INFO - Running main action method: populate_webroot 11:17:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:17:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:17:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:17:48 INFO - '--tppagecycles', 11:17:48 INFO - '7'], 11:17:48 INFO - 'tests': ['tsvgm']}}, 11:17:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:17:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:17:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:17:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:17:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['damp', 'tps']}, 11:17:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'tests': ['damp', 'tps']}, 11:17:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['dromaeo_dom']}, 11:17:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:17:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['basic_compositor_video']}, 11:17:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:17:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['a11yr', 11:17:48 INFO - 'ts_paint', 11:17:48 INFO - 'tpaint', 11:17:48 INFO - 'sessionrestore', 11:17:48 INFO - 'sessionrestore_no_auto_restore', 11:17:48 INFO - 'tabpaint']}, 11:17:48 INFO - 'other-e10s': {'tests': ['a11yr', 11:17:48 INFO - 'ts_paint', 11:17:48 INFO - 'tpaint', 11:17:48 INFO - 'sessionrestore', 11:17:48 INFO - 'sessionrestore_no_auto_restore', 11:17:48 INFO - 'tabpaint']}, 11:17:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:17:48 INFO - 'ts_paint', 11:17:48 INFO - 'tpaint', 11:17:48 INFO - 'sessionrestore', 11:17:48 INFO - 'sessionrestore_no_auto_restore', 11:17:48 INFO - 'tabpaint']}, 11:17:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:17:48 INFO - 'ts_paint', 11:17:48 INFO - 'tpaint', 11:17:48 INFO - 'sessionrestore', 11:17:48 INFO - 'sessionrestore_no_auto_restore', 11:17:48 INFO - 'tabpaint']}, 11:17:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['a11yr', 11:17:48 INFO - 'ts_paint', 11:17:48 INFO - 'tpaint', 11:17:48 INFO - 'sessionrestore', 11:17:48 INFO - 'sessionrestore_no_auto_restore', 11:17:48 INFO - 'tabpaint']}, 11:17:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['a11yr', 11:17:48 INFO - 'ts_paint', 11:17:48 INFO - 'tpaint', 11:17:48 INFO - 'sessionrestore', 11:17:48 INFO - 'sessionrestore_no_auto_restore', 11:17:48 INFO - 'tabpaint']}, 11:17:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['tsvgx', 11:17:48 INFO - 'tsvgr_opacity', 11:17:48 INFO - 'tart', 11:17:48 INFO - 'tscrollx', 11:17:48 INFO - 'cart', 11:17:48 INFO - 'tsvg_static']}, 11:17:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:17:48 INFO - 'tsvgr_opacity', 11:17:48 INFO - 'tart', 11:17:48 INFO - 'tscrollx', 11:17:48 INFO - 'cart', 11:17:48 INFO - 'tsvg_static']}, 11:17:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'talos_options': ['--disable-e10s'], 11:17:48 INFO - 'tests': ['tp5o']}, 11:17:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:17:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'talos_options': ['--disable-e10s', 11:17:48 INFO - '--xperf_path', 11:17:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:48 INFO - 'tests': ['tp5n']}, 11:17:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:17:48 INFO - 'talos_options': ['--xperf_path', 11:17:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:48 INFO - 'tests': ['tp5n']}}, 11:17:48 INFO - 'talos.zip': {'path': '', 11:17:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:17:48 INFO - Running post-action listener: _resource_record_post_action 11:17:48 INFO - [mozharness: 2017-05-24 18:17:48.182592Z] Finished populate-webroot step (success) 11:17:48 INFO - [mozharness: 2017-05-24 18:17:48.182726Z] Running create-virtualenv step. 11:17:48 INFO - Running pre-action listener: _resource_record_pre_action 11:17:48 INFO - Running main action method: create_virtualenv 11:17:48 INFO - Creating virtualenv /builds/slave/test/build/venv 11:17:48 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 11:17:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:17:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:17:48 INFO - Using real prefix '/tools/python27' 11:17:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:17:49 INFO - Installing distribute.............................................................................................................................................................................................done. 11:17:51 INFO - Installing pip.................done. 11:17:51 INFO - Return code: 0 11:17:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:17:52 INFO - Reading from file tmpfile_stdout 11:17:52 INFO - Output received: 11:17:52 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) 11:17:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:17:52 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')]} 11:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:52 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')]} 11:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:52 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:17:52 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 11:17:52 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 11:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:52 INFO - 'HOME': '/Users/cltbld', 11:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:52 INFO - 'LOGNAME': 'cltbld', 11:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:52 INFO - 'MOZ_NO_REMOTE': '1', 11:17:52 INFO - 'NO_EM_RESTART': '1', 11:17:52 INFO - 'PAGER': '/bin/cat', 11:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:52 INFO - 'PWD': '/builds/slave/test', 11:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:52 INFO - 'SHELL': '/bin/bash', 11:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:17:52 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:52 INFO - 'USER': 'cltbld', 11:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:52 INFO - 'XPC_FLAGS': '0x0', 11:17:52 INFO - 'XPC_SERVICE_NAME': '0', 11:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:52 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 11:17:52 INFO - Cleaning up... 11:17:52 INFO - Return code: 0 11:17:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:17:52 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')]} 11:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:52 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')]} 11:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:52 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:17:52 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 11:17:52 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 11:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:52 INFO - 'HOME': '/Users/cltbld', 11:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:52 INFO - 'LOGNAME': 'cltbld', 11:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:52 INFO - 'MOZ_NO_REMOTE': '1', 11:17:52 INFO - 'NO_EM_RESTART': '1', 11:17:52 INFO - 'PAGER': '/bin/cat', 11:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:52 INFO - 'PWD': '/builds/slave/test', 11:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:52 INFO - 'SHELL': '/bin/bash', 11:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:17:52 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:52 INFO - 'USER': 'cltbld', 11:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:52 INFO - 'XPC_FLAGS': '0x0', 11:17:52 INFO - 'XPC_SERVICE_NAME': '0', 11:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:52 INFO - Downloading/unpacking psutil>=3.1.1 11:17:52 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 11:17:52 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 11:17:52 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 11:17:52 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 11:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:17:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:54 INFO - Installing collected packages: psutil 11:17:54 INFO - Running setup.py install for psutil 11:17:54 INFO - building 'psutil._psutil_osx' extension 11:17:54 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 11:17:55 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 11:17:55 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 11:17:55 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 11:17:55 INFO - building 'psutil._psutil_posix' extension 11:17:55 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 11:17:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:17:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:17:55 INFO - ^ 11:17:55 INFO - 1 warning generated. 11:17:55 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 11:17:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:55 INFO - Successfully installed psutil 11:17:55 INFO - Cleaning up... 11:17:55 INFO - Return code: 0 11:17:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:55 INFO - 'HOME': '/Users/cltbld', 11:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:55 INFO - 'LOGNAME': 'cltbld', 11:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:55 INFO - 'MOZ_NO_REMOTE': '1', 11:17:55 INFO - 'NO_EM_RESTART': '1', 11:17:55 INFO - 'PAGER': '/bin/cat', 11:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:55 INFO - 'PWD': '/builds/slave/test', 11:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:55 INFO - 'SHELL': '/bin/bash', 11:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:17:55 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:55 INFO - 'USER': 'cltbld', 11:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:55 INFO - 'XPC_FLAGS': '0x0', 11:17:55 INFO - 'XPC_SERVICE_NAME': '0', 11:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:17:56 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 11:17:56 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 11:17:56 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 11:17:56 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 11:17:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:17:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:17:57 INFO - Installing collected packages: mozsystemmonitor 11:17:57 INFO - Running setup.py install for mozsystemmonitor 11:17:57 INFO - Successfully installed mozsystemmonitor 11:17:57 INFO - Cleaning up... 11:17:57 INFO - Return code: 0 11:17:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:57 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:17:57 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 11:17:57 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 11:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:57 INFO - 'HOME': '/Users/cltbld', 11:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:57 INFO - 'LOGNAME': 'cltbld', 11:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:57 INFO - 'MOZ_NO_REMOTE': '1', 11:17:57 INFO - 'NO_EM_RESTART': '1', 11:17:57 INFO - 'PAGER': '/bin/cat', 11:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:57 INFO - 'PWD': '/builds/slave/test', 11:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:57 INFO - 'SHELL': '/bin/bash', 11:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:17:57 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:57 INFO - 'USER': 'cltbld', 11:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:57 INFO - 'XPC_FLAGS': '0x0', 11:17:57 INFO - 'XPC_SERVICE_NAME': '0', 11:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:58 INFO - Downloading/unpacking jsonschema==2.5.1 11:17:58 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 11:17:58 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 11:17:58 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 11:17:58 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 11:17:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:17:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:00 INFO - Downloading functools32-3.2.3-2.tar.gz 11:18:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:18:00 INFO - warning: no files found matching '*.txt' 11:18:00 INFO - no previously-included directories found matching 'build' 11:18:00 INFO - no previously-included directories found matching 'dist' 11:18:00 INFO - no previously-included directories found matching '.git*' 11:18:00 INFO - Installing collected packages: jsonschema, functools32 11:18:00 INFO - Running setup.py install for functools32 11:18:00 INFO - warning: no files found matching '*.txt' 11:18:00 INFO - no previously-included directories found matching 'build' 11:18:00 INFO - no previously-included directories found matching 'dist' 11:18:00 INFO - no previously-included directories found matching '.git*' 11:18:00 INFO - Successfully installed jsonschema functools32 11:18:00 INFO - Cleaning up... 11:18:00 INFO - Return code: 0 11:18:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:18: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')]} 11:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18: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')]} 11:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:00 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:18:00 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 11:18:00 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 11:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:00 INFO - 'HOME': '/Users/cltbld', 11:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:00 INFO - 'LOGNAME': 'cltbld', 11:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:00 INFO - 'MOZ_NO_REMOTE': '1', 11:18:00 INFO - 'NO_EM_RESTART': '1', 11:18:00 INFO - 'PAGER': '/bin/cat', 11:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:00 INFO - 'PWD': '/builds/slave/test', 11:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:00 INFO - 'SHELL': '/bin/bash', 11:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:00 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:00 INFO - 'USER': 'cltbld', 11:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:00 INFO - 'XPC_FLAGS': '0x0', 11:18:00 INFO - 'XPC_SERVICE_NAME': '0', 11:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:18:01 INFO - Cleaning up... 11:18:01 INFO - Return code: 0 11:18:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:01 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:18:01 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 11:18:01 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 11:18:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:01 INFO - 'HOME': '/Users/cltbld', 11:18:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:01 INFO - 'LOGNAME': 'cltbld', 11:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:01 INFO - 'MOZ_NO_REMOTE': '1', 11:18:01 INFO - 'NO_EM_RESTART': '1', 11:18:01 INFO - 'PAGER': '/bin/cat', 11:18:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:01 INFO - 'PWD': '/builds/slave/test', 11:18:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:01 INFO - 'SHELL': '/bin/bash', 11:18:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:01 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:01 INFO - 'USER': 'cltbld', 11:18:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:01 INFO - 'XPC_FLAGS': '0x0', 11:18:01 INFO - 'XPC_SERVICE_NAME': '0', 11:18:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:02 INFO - Downloading blobuploader-1.2.4.tar.gz 11:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:18:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:18:02 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 11:18:02 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 11:18:02 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 11:18:02 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 11:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:18:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:18:03 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 11:18:03 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 11:18:03 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 11:18:03 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 11:18:03 INFO - Downloading docopt-0.6.1.tar.gz 11:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:18:03 INFO - Installing collected packages: blobuploader, requests, docopt 11:18:03 INFO - Running setup.py install for blobuploader 11:18:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:18:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:18:03 INFO - Running setup.py install for requests 11:18:04 INFO - Running setup.py install for docopt 11:18:04 INFO - Successfully installed blobuploader requests docopt 11:18:04 INFO - Cleaning up... 11:18:04 INFO - Return code: 0 11:18:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:04 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:18:04 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 11:18:04 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 11:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:04 INFO - 'HOME': '/Users/cltbld', 11:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:04 INFO - 'LOGNAME': 'cltbld', 11:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:04 INFO - 'MOZ_NO_REMOTE': '1', 11:18:04 INFO - 'NO_EM_RESTART': '1', 11:18:04 INFO - 'PAGER': '/bin/cat', 11:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:04 INFO - 'PWD': '/builds/slave/test', 11:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:04 INFO - 'SHELL': '/bin/bash', 11:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:04 INFO - 'USER': 'cltbld', 11:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:04 INFO - 'XPC_FLAGS': '0x0', 11:18:04 INFO - 'XPC_SERVICE_NAME': '0', 11:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:18:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-O9a42e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:18:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1ADY2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9V4D3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-qclFn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tfmuXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-0wnfgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1Uwd_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hnqjcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Mqtszw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-s4rhVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:18:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-6tdLkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:18:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Ct9tIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:18:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Gsa9RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:18:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QOs2yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YYScvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:18:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-MvzJLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:18:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-3A4Zsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:18:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:18:06 INFO - Running setup.py install for manifestparser 11:18:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:18:06 INFO - Running setup.py install for mozcrash 11:18:06 INFO - Running setup.py install for mozdebug 11:18:07 INFO - Running setup.py install for mozdevice 11:18:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Running setup.py install for mozfile 11:18:07 INFO - Running setup.py install for mozhttpd 11:18:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Running setup.py install for mozinfo 11:18:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Running setup.py install for mozInstall 11:18:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:18:07 INFO - Running setup.py install for mozleak 11:18:07 INFO - Running setup.py install for mozlog 11:18:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:18:08 INFO - Running setup.py install for moznetwork 11:18:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:18:08 INFO - Running setup.py install for mozprocess 11:18:08 INFO - Running setup.py install for mozprofile 11:18:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:18:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:18:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:18:08 INFO - Running setup.py install for mozrunner 11:18:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:18:08 INFO - Running setup.py install for mozscreenshot 11:18:08 INFO - Running setup.py install for moztest 11:18:09 INFO - Running setup.py install for mozversion 11:18:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:18:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:18:09 INFO - Cleaning up... 11:18:09 INFO - Return code: 0 11:18:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:09 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:18:09 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 11:18:09 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 11:18:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:09 INFO - 'HOME': '/Users/cltbld', 11:18:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:09 INFO - 'LOGNAME': 'cltbld', 11:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:09 INFO - 'MOZ_NO_REMOTE': '1', 11:18:09 INFO - 'NO_EM_RESTART': '1', 11:18:09 INFO - 'PAGER': '/bin/cat', 11:18:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:09 INFO - 'PWD': '/builds/slave/test', 11:18:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:09 INFO - 'SHELL': '/bin/bash', 11:18:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:09 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:09 INFO - 'USER': 'cltbld', 11:18:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:09 INFO - 'XPC_FLAGS': '0x0', 11:18:09 INFO - 'XPC_SERVICE_NAME': '0', 11:18:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:18:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-eQic2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:18:09 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)) 11:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:18:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-5QiWeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:18:09 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)) 11:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:18:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-XjaaAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:18:09 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)) 11:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:18:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-oaXyl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:18:09 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)) 11:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:18:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-PdtZDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-vMftij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-mijYhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-GeuDnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-v65W77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-75n9hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-O1Mwle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-B8yP0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ZIEswR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:18:10 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)) 11:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:18:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iD014E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:18:11 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)) 11:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8n2Y3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:11 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)) 11:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:18:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-y5rxE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:18:11 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)) 11:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:18:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ZVUlp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:18:11 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)) 11:18:11 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)) 11:18:11 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)) 11:18:11 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)) 11:18:11 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)) 11:18:11 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)) 11:18:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:12 INFO - Downloading blessings-1.6.tar.gz 11:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:18:12 INFO - Installing collected packages: blessings 11:18:12 INFO - Running setup.py install for blessings 11:18:13 INFO - Successfully installed blessings 11:18:13 INFO - Cleaning up... 11:18:13 INFO - Return code: 0 11:18:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:13 INFO - Reading from file tmpfile_stdout 11:18:13 INFO - Current package versions: 11:18:13 INFO - blessings == 1.6 11:18:13 INFO - blobuploader == 1.2.4 11:18:13 INFO - docopt == 0.6.1 11:18:13 INFO - functools32 == 3.2.3-2 11:18:13 INFO - jsonschema == 2.5.1 11:18:13 INFO - manifestparser == 1.1 11:18:13 INFO - mozInstall == 1.12 11:18:13 INFO - mozcrash == 1.0 11:18:13 INFO - mozdebug == 0.1 11:18:13 INFO - mozdevice == 0.49 11:18:13 INFO - mozfile == 1.2 11:18:13 INFO - mozhttpd == 0.7 11:18:13 INFO - mozinfo == 0.9 11:18:13 INFO - mozleak == 0.1 11:18:13 INFO - mozlog == 3.4 11:18:13 INFO - moznetwork == 0.27 11:18:13 INFO - mozprocess == 0.25 11:18:13 INFO - mozprofile == 0.28 11:18:13 INFO - mozrunner == 6.13 11:18:13 INFO - mozscreenshot == 0.1 11:18:13 INFO - mozsystemmonitor == 0.3 11:18:13 INFO - moztest == 0.8 11:18:13 INFO - mozversion == 1.4 11:18:13 INFO - psutil == 3.1.1 11:18:13 INFO - requests == 1.2.3 11:18:13 INFO - wsgiref == 0.1.2 11:18:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18: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')]} 11:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18: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')]} 11:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:13 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 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:18:13 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 11:18:13 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 11:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:13 INFO - 'HOME': '/Users/cltbld', 11:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:13 INFO - 'LOGNAME': 'cltbld', 11:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:13 INFO - 'MOZ_NO_REMOTE': '1', 11:18:13 INFO - 'NO_EM_RESTART': '1', 11:18:13 INFO - 'PAGER': '/bin/cat', 11:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:13 INFO - 'PWD': '/builds/slave/test', 11:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:13 INFO - 'SHELL': '/bin/bash', 11:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:13 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:13 INFO - 'USER': 'cltbld', 11:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:13 INFO - 'XPC_FLAGS': '0x0', 11:18:13 INFO - 'XPC_SERVICE_NAME': '0', 11:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 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)) 11:18:13 INFO - Cleaning up... 11:18:13 INFO - Return code: 0 11:18:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:18: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')]} 11:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18: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')]} 11:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f748b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f74b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf58458f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, '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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 11:18: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'] in /builds/slave/test/build 11:18: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 11:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:13 INFO - 'HOME': '/Users/cltbld', 11:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:13 INFO - 'LOGNAME': 'cltbld', 11:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:13 INFO - 'MOZ_NO_REMOTE': '1', 11:18:13 INFO - 'NO_EM_RESTART': '1', 11:18:13 INFO - 'PAGER': '/bin/cat', 11:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:13 INFO - 'PWD': '/builds/slave/test', 11:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:13 INFO - 'SHELL': '/bin/bash', 11:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:13 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:13 INFO - 'USER': 'cltbld', 11:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:13 INFO - 'XPC_FLAGS': '0x0', 11:18:13 INFO - 'XPC_SERVICE_NAME': '0', 11:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:18:13 INFO - Cleaning up... 11:18:13 INFO - Return code: 0 11:18:13 INFO - Running post-action listener: _resource_record_post_action 11:18:13 INFO - Running post-action listener: _start_resource_monitoring 11:18:14 INFO - Starting resource monitoring. 11:18:14 INFO - [mozharness: 2017-05-24 18:18:14.014594Z] Finished create-virtualenv step (success) 11:18:14 INFO - [mozharness: 2017-05-24 18:18:14.015594Z] Running install step. 11:18:14 INFO - Running pre-action listener: _resource_record_pre_action 11:18:14 INFO - Running main action method: install 11:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:14 INFO - Reading from file tmpfile_stdout 11:18:14 INFO - Detecting whether we're running mozinstall >=1.0... 11:18:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:18:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:18:14 INFO - Reading from file tmpfile_stdout 11:18:14 INFO - Output received: 11:18:14 INFO - Usage: mozinstall [options] installer 11:18:14 INFO - Options: 11:18:14 INFO - -h, --help show this help message and exit 11:18:14 INFO - -d DEST, --destination=DEST 11:18:14 INFO - Directory to install application into. [default: 11:18:14 INFO - "/builds/slave/test"] 11:18:14 INFO - --app=APP Application being installed. [default: firefox] 11:18:14 INFO - mkdir: /builds/slave/test/build/application 11:18:14 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'] 11:18:14 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 11:18:29 INFO - Reading from file tmpfile_stdout 11:18:29 INFO - Output received: 11:18:29 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:18:29 INFO - Running post-action listener: _resource_record_post_action 11:18:29 INFO - [mozharness: 2017-05-24 18:18:29.513510Z] Finished install step (success) 11:18:29 INFO - [mozharness: 2017-05-24 18:18:29.513652Z] Running run-tests step. 11:18:29 INFO - Running pre-action listener: _resource_record_pre_action 11:18:29 INFO - Running main action method: run_tests 11:18:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:18:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:18:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:18:29 INFO - 2017-05-24 11:18:29.528 screenresolution[1622:7283] starting screenresolution argv=screenresolution get 11:18:29 INFO - 2017-05-24 11:18:29.530 screenresolution[1622:7283] Display 0: 1600x1200x32@60 11:18:29 INFO - 2017-05-24 11:18:29.536 screenresolution[1623:7285] starting screenresolution argv=screenresolution list 11:18:29 INFO - Available Modes on Display 0 11:18:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:18:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:18:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:18:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:18:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:18:29 INFO - Intel Iris: 11:18:29 INFO - Chipset Model: Intel Iris 11:18:29 INFO - Type: GPU 11:18:29 INFO - Bus: Built-In 11:18:29 INFO - VRAM (Dynamic, Max): 1536 MB 11:18:29 INFO - Vendor: Intel (0x8086) 11:18:29 INFO - Device ID: 0x0a2e 11:18:29 INFO - Revision ID: 0x0009 11:18:29 INFO - Displays: 11:18:29 INFO - Display: 11:18:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:18:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:18:29 INFO - Display Serial Number: 1600x1200 60 11:18:29 INFO - Main Display: Yes 11:18:29 INFO - Mirror: Off 11:18:29 INFO - Online: Yes 11:18:29 INFO - Rotation: Supported 11:18:29 INFO - Return code: 0 11:18:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:18:29 INFO - Python 2.7.3 11:18:29 INFO - Return code: 0 11:18:29 INFO - grabbing minidump binary from tooltool 11:18:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:29 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 0x10f526d30>, '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 0x10f44ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f51bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:18:29 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 11:18:29 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 11:18:29 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 11:18:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:18:29 INFO - Return code: 0 11:18:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:18:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:18:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:18:29 INFO - ENV: RUST_BACKTRACE is now 1 11:18:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:18:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:18:29 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/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 11:18:29 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/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --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 11:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VoohEuhXQr/Render', 11:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:29 INFO - 'HOME': '/Users/cltbld', 11:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:29 INFO - 'LOGNAME': 'cltbld', 11:18:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:18:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:29 INFO - 'MOZ_NO_REMOTE': '1', 11:18:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:18:29 INFO - 'NO_EM_RESTART': '1', 11:18:29 INFO - 'PAGER': '/bin/cat', 11:18:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:29 INFO - 'PWD': '/builds/slave/test', 11:18:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:29 INFO - 'RUST_BACKTRACE': '1', 11:18:29 INFO - 'SHELL': '/bin/bash', 11:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkwqsUjZ8U/Listeners', 11:18:29 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 11:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:29 INFO - 'USER': 'cltbld', 11:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:29 INFO - 'XPC_FLAGS': '0x0', 11:18:29 INFO - 'XPC_SERVICE_NAME': '0', 11:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:29 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/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 11:18:30 INFO - mozversion application_buildid: 20170524100420 11:18:30 INFO - mozversion application_changeset: f7c942b561d57638df5c1c2542dd3d946dc71907 11:18:30 INFO - mozversion application_display_name: Firefox 11:18:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:18:30 INFO - mozversion application_name: Firefox 11:18:30 INFO - mozversion application_remotingname: firefox 11:18:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:18:30 INFO - mozversion application_vendor: Mozilla 11:18:30 INFO - mozversion application_version: 54.0 11:18:30 INFO - mozversion platform_buildid: 20170524100420 11:18:30 INFO - mozversion platform_changeset: f7c942b561d57638df5c1c2542dd3d946dc71907 11:18:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:18:30 INFO - mozversion platform_version: 54.0 11:18:30 INFO - using testdate: 1495649910 11:18:30 INFO - actual date: 1495649910 11:18:30 INFO - starting webserver on 'localhost:49220' 11:18:30 INFO - SUITE-START | Running 2 tests 11:18:30 INFO - TEST-START | dromaeo_css 11:18:30 INFO - operating with platform_type : mac_ 11:18:30 INFO - Initialising browser for dromaeo_css test... 11:18:30 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpwzSuVq/profile http://localhost:49220/getInfo.html) 11:18:32 INFO - PROCESS | 1633 | 2017-05-24 11:18:32.969 plugin-container[1636:7460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 11:18:32 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:32 INFO - PROCESS | 1633 | 2017-05-24 11:18:32.970 plugin-container[1636:7460] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d0f, name = 'com.apple.CFPasteboardClient' 11:18:32 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:32 INFO - PROCESS | 1633 | 2017-05-24 11:18:32.970 plugin-container[1636:7460] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:18:33 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 11:18:33 INFO - PROCESS | 1633 | colorDepth:24 11:18:33 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 11:18:33 INFO - PROCESS | 1633 | __metrics 11:18:33 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:18:33 INFO - TEST-INFO | 1633: exit 0 11:18:33 INFO - Browser initialized. 11:18:33 INFO - Running cycle 1/1 for dromaeo_css test... 11:18:33 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpwzSuVq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:18:35 INFO - PROCESS | 1637 | 2017-05-24 11:18:35.930 plugin-container[1638:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 11:18:35 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:35 INFO - PROCESS | 1637 | 2017-05-24 11:18:35.930 plugin-container[1638:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860f, name = 'com.apple.CFPasteboardClient' 11:18:35 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:18:35 INFO - PROCESS | 1637 | 2017-05-24 11:18:35.930 plugin-container[1638:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:18:45 INFO - PROCESS | 1637 | RSS: Main: 174538752 11:18:45 INFO - PROCESS | 1637 | 11:21:48 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49220/tests/dromaeo/cssquery-ext.html) 11:21:50 INFO - PROCESS | 1637 | RSS: Main: 189865984 11:21:50 INFO - PROCESS | 1637 | 11:24:52 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-ext.html (next: http://localhost:49220/tests/dromaeo/cssquery-jquery.html) 11:24:53 INFO - PROCESS | 1637 | RSS: Main: 203452416 11:24:53 INFO - PROCESS | 1637 | 11:27:54 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49220/tests/dromaeo/cssquery-mootools.html) 11:27:55 INFO - PROCESS | 1637 | RSS: Main: 206450688 11:27:55 INFO - PROCESS | 1637 | 11:30:57 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49220/tests/dromaeo/cssquery-prototype.html) 11:30:58 INFO - PROCESS | 1637 | RSS: Main: 209506304 11:30:58 INFO - PROCESS | 1637 | 11:34:01 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49220/tests/dromaeo/cssquery-yui.html) 11:34:01 INFO - PROCESS | 1637 | RSS: Main: 211996672 11:34:01 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49220/tests/dromaeo/cssquery-yui.html (next: http://localhost:49220/tests/dromaeo/cssquery-dojo.html) 11:37:04 INFO - PROCESS | 1637 | RSS: Main: 210010112 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | __start_tp_report 11:37:04 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 11:37:04 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 11:37:04 INFO - PROCESS | 1637 | |i|pagename|runs| 11:37:04 INFO - PROCESS | 1637 | |0;dojo.html;3149.4016136933983;3181.822725591531;3688.2992231476032;3693.4644476550884;3703.6851867591254;5504.366997795255;5558.888822223563;6145.924463218941;6241.8127456176335;6274.215723034621;6227.875442491148;6234;6245.375462453752;6248.562600617958;6257.624542527452;5652.830415087554;5673.319201695792;5731.570132240078;5761.4814666679995;5796.275465566804;4949.381327334083;5856.180134781134;5872.530197584204;5880.323762767293;5919.289685237777;5566.276384070065;5591.161325801122;5780.393058728836;5822.505087067583;5833.533317334603;5692.430756924296;5711.171880077365;5722.399148089432;5735.770569177228;5776.97111514444;5548.944510554883;5705.144228365737;5705.2012718219485;5738.598298119092;5738.626989245686;6061.121137435091;6193.6903154842075;6256.280527739323;6283.4344908957955;6295.213098362705;6372.98032314832;6537;6565.113709649232;6601.6699165041555;6609.966950165219;6732.528722989393;6797.932020679828;6800;6807.523473356867;6818.28408017156;6288.779892703734;6296.685165741695;6316.1788967434395;6318.241810982666;6320.5891617044745;6587.670616469253;6620.139381880324;6655.600663960178;6781.186257649114;6868.694947959872;6593.8351541210895;6600.53796234259;6616.305287944697;6616.4045235929;6640.734370625121;5375.596830237748;6723.039983296807;6748.493862960199;6771.72913083481;6774.898376524356;6356.364363563606;6414.422701956846;6442.8389290268115;6454.19322584677;6644.534882558174;6539.607623542603;6551.213854337415;6589.044588534635;6609.339066093448;6610.933890661128;5214.191800270964;5238.633295669343;5312.76092575835;5360.865978350573;5366.651167674088;4748.240281554935;4794.880127996828;4805.831795887128;4828.179209534387;4835.685680430761;6233.688315584202;6314.0528920661345;6318.842028949313;6329.778457753957;6335.904961425582;6516.152900122954;6639;6653.6340501271925;6722.462203023746;6732.932670673328;6324.778632747832;6328.588641738273;6332.778352757631;6344.746210151635;6344.873102538016;5963.612365196248;5981.043033114681;5984.73068711909;5996.940030599638;5998;5390.541803946709;6210.4100110489435;6615.834604134935;6698.062271282024;6791.592504449749;6758.4931130166415;6800.557963732342;6803.081583986099;6814.591124532544;6817.761378351932;6863.416609588241;6929.792106236821;6965.303469652994;6977.2673869243;6989.231184569795;6326.272478665012;6352.0789485525265;6550.868982620225;6559.147310849559;6705.2959439258175;6764.323567643196;6778.2205046420295;6779.15260592426;6794;6811.625360605214;6497;6503.772367967098;6519.152510173837;6531.934680653133;6543.967280163569;5455.890882182413;5939.693267481147;6427.132337134427;6536.575122617205;6583.868322633616;6301.653409062362;6341.302456729848;6362.141110949962;6424.614523128627;6425.742970281323;6533.542652014314;6555.639439830855;6558.606483610999;6560.245571759308;6583.769568065095;4928.334674818853;6179.474744646755;6223.159873417031;6324.114623952558;6442.098106265033 11:37:04 INFO - PROCESS | 1637 | |1;ext.html;769.3460558525251;771.2056581720827;846.767139036765;976.8144052629996;976.8485884687872;39061.828145155654;40161;41486;41765;41827;40003.19993600126;40145.59854401455;40234.19531609366;40255;40504.987375315555;8193.30356919661;8339.20777526134;11198.832017519744;11479.368634725086;11633.127515436334;11171.49728262226;11198.776024479506;11247.83128253077;11272;11291.830622540627;9310.674126405576;9360.344775865693;11192.776144477106;11201.551937922473;11224.49489772958;9877.703668889944;11214.66356009321;11224.045956093702;11247.718807029849;11248.987591116756;11065.723356916062;11134.888651113466;11180.664580062612;11183.161262905232;11196.832047519294;27438.725612743816;35195.64804351949;35442;35487.82256088729;35502;3850.595687452805;3895.9805200973815;3901.51231096113;3909.9218015639813;3927.351986922183;26110;26424.73575264242;26455.603165952525;26500.60249096265;27003.78482968271;3158.2262345725217;3211.759118066131;3213.53403756454;3265.804051756902;3335.1495368681076;323629;326946;347663;355088;355477;36097;38023.809880950415;46206.537934620894;46422.767886160356;46472.7676361616;35468;39592.802035990215;48520.544383668544;48634.27048594274;48677.26984095242;14398;14511.854881451261;14556.781648275284;14604.269786510844;14610.415583376758;12644;12713.110082294335;12730.745385092432;12748.426320815586;12753.681157971125;11992.460339284753;12216.26702397859;12298.508059677692;12320.075994300463;12379.6905077372;5856.677882716406;5871.207387002785;5887.146363777314;5903.586748927534;5915.201447804578;1016.3596933931724;1062.4262898034974;1151.5163631274884;1167.4104577188466;1188.2454641302836;26982.32544186372;27109.78005989735;27196.32009199786;27298.727012730014;27481;1047.2773786087575;1110.2228440091806;1263.216805580546;1298.532528289815;1333.7865941449322;13662.658433539042;13909;14187.929060354632;14244.715105697827;14560.053596516193;501572;503519;504026;504889;506190;58243.70878145667;58370;58440.7077964616;58456;58566.707166464745;1505.3226048278318;1529.258309719785;1547.3423794887353;1553.4873491747696;1560.9297581608628;25292;26542.734572654026;26919.730802691724;26961.517116557992;27138;47572.52427475681;47617.761911190224;47756.5224347766;47762.52237477581;47938.56184314476;456334;459147.40852591046;459552;460941;461123;27377.589336159574;27780.583291251052;32570.83714581412;32731;32857.835710821295;44982.77508612436;45157.096858061996;45201.54798452105;45861.77069114633;46663.76668116773;31368.843155784074;31537.896173633813;31673.5248971261;31724;32257.67742322547;30770.846145770025;31114.844425777726;31433;31690.366192675556;31952.36095278128;36532.634673653985;36819.263614728094;37105.77365358087;37171.256574868894;37292.254154916205;25869.611955820303;31271.374572507968;31404.528932066496;31679.20801979969;31906.52140217852;21706.457338566663;22941.197058103553;24001.270118478267;24996.500069998863;25497.872510637328 11:37:04 INFO - PROCESS | 1637 | |2;jquery.html;426.41793951256545;439.93840862279285;444.94438195225587;449.84480354277764;455.2283878825391;21238.89380553097;22261.77738222619;22693.999999999978;23195.420114497145;23544.76455235447;19003.714944275845;22992;23299.417514562145;24259.39351516213;24425.511489770237;6914.5159838811305;7015.123109611286;7121;7212.423006159521;7289.453291003196;7054.29457054293;7060.293970602925;7130.8573828523395;7680.462367634268;8025.438219324649;8045.115037345888;8122.18778122189;8123.837523249532;8129.430939834214;8158.959205203966;7772.689092436324;7993.240642138988;8017.278444939983;8019.799505012363;8046.718364857261;7516;7740.148583655793;7796.947412099407;7810.960945195295;7824.804379890491;26121;26788.86605566979;26824.73175268242;27339.179824605293;27624.171274861787;3025.273934255763;3046.7105624965593;3074.723274905269;3137.2313783123054;3148.7008734170217;15886.285117169755;16202.432914848041;16739.916300418543;16825.66348673032;17152.970821750736;2100.2649072824543;2119.13115622594;2159.5680863827297;2188.070070220166;2213.2143089203423;130213;162683.1865840675;166567;168867;168956.15521922434;20559.738428772824;21926.671099933516;22174.889125554593;22300.66549001766;22539.87300634962;33250.16874578106;33380;34057.48913766295;34101.317973640886;34116;10800.351978881292;10965.61620343284;11147.72130696739;11203.271787333797;11508.964193222482;9256.444613323221;9291.117343852324;9311.115444032806;9481.952590237142;9539.950605433396;7860.489068210548;9558.091981261769;9613;9838.458884761263;9846.113849753414;3914.5106861642294;4080.6939479539155;4280.229558679405;4398.3402489626815;4421.182081314967;862.6333808131519;901.5492253873064;902.8058967322057;965;967.7483854197818;21972.230971915906;22326;22435.663465048037;22539;22839.543209135725;775.848709501653;952.0578020837553;952.8142012307673;976.1019861727086;992.7220378294089;8331.33349332052;10847.67456976292;11261.155413343868;11408.600698975495;11431.971122599007;695696;764763;771468;791898;792368;130160;130617;138225.9266111009;146619.2669036648;161122.38877611308;1412.7245187188605;1428.2644438114587;1450.2676148544917;1480.696457226266;1494.2230040378943;19828.801711982695;20520.973951302673;20779;20847;21169.6824547635;46824.297635536175;56029;59579;63191.6840415795;64350.35649643631;77778;82281.58859205665;84414.57792711243;84768;85141.87145321169;10825.458727063773;10939.945300273448;11162.218644694793;11395.943020285176;11433.771324573629;12353.71131032695;12384.31886246229;12679.112462127932;12769.61691149267;12816.231026138461;10918.836217456586;11067.225294229327;11251.887481125084;11268.661940141808;11327.773444531229;10279.53742081623;10344.896551034393;10359.689209323733;10374.740631484274;10435.426051567236;11109.00018998294;11187.496562654538;11306.83039754388;11566.537338506365;11632.651020469399;11517.136214783757;11526.59656912004;11530.250533715282;11684.1236907234;11874.703132421413;10967.725806854893;11003;11250.831237531607;11333.546658133582;11394.48724807369 11:37:04 INFO - PROCESS | 1637 | |3;mootools.html;1478.534261707562;1627.7151498487763;1655.4619748581713;1663.3429795230884;1667.1164282930047;11090.390028548427;11673;11778.175527713052;11808.822867656992;12071.51713931444;11528.884711152887;11650.650480485578;11738.7652246955;11758.23571467854;11759.647210583675;2229.2754854672257;2325.5697695926306;2485.9751402485927;2492.1775813981344;2506.987465062672;2408.2654790288957;2472.196536125757;2482.478679477303;2495.3137887081016;2499.4376265340243;2406.181898154627;2446.9388265293333;2453.6442215878687;2477.665515155458;2478.8512689238614;2370.940726481852;2416;2416.5287768885073;2416.891239894209;2461.310832966772;2431.234161239204;2449.0571130114854;2450.2036838027534;2464.4578192797558;2474.9628755568683;11350.829737553944;11494.942525287404;11541.076713862954;11594.76810463786;11621.6513504595;6042.154098426224;6105.786297479612;6199.907001394983;6200.720967556471;6206.689665516751;3160.652328243891;3677.191017976041;3775.4336849472525;3775.4903088083033;3820.0449887528116;1250.4783725888785;1261.369315342329;1285.254552359629;1309.9410526526328;1315.7105436803888;14142.939279554075;14366.066205696596;15193.924030379778;15926.362945482168;16075;4817.108834865561;4817.602895160434;4985.800567977313;5002.349694539686;5048.747562621854;9249.583768730423;9798.608055677692;12226.960708339891;12250.081243906743;12335.876641233652;5482.5613950883835;5922.312141039783;6145.201123853871;6145.231846019247;6157.753689852445;5386.245925570423;5452.727363631802;5458.699771512525;5496.615236933456;5569.498745112959;4073.839559690213;4112.650424713873;4164.03534507193;4203.243416185116;4215.810288536962;1087.5867170475326;1088.3687461272443;1090.841827934945;1105.6179775280898;1106.4578356605205;764.58712295361;938.7230766923774;942.4486675295029;942.7266092833139;979.6816034788723;6616.437602803816;6697.665116744143;6807.965960170168;6862.382385585322;7159.534630249017;808.640155130961;958.4057884111895;1002.1231422505308;1028.89711028898;1040.5213601743283;4283.721558098714;4319.892002699895;4336.3278691802825;4363.38912552243;4422.447194100738;13686.178829270275;14130.364133613806;14133.15201087938;14261.714765704628;14264.714705705828;13272.867271327163;13816.654583635489;14081.014328997077;14164.929175354056;14286.928565357106;8977.910220897707;9021.052789457144;9206.493642849708;10402.791944161074;10613.73465663349;5837.328707198557;6117.571769976237;6889.897616381355;6979.790606281819;7021.613811240431;11825.231359961575;11896.524139034687;12045.939770301093;12121.515139394325;12170.939145304217;12162.452689628812;12197.02423806111;12197.085218608463;12401.689957751129;12721.93639031799;9494.620215191315;9786.951065244913;9795;9796.461194634363;9804.166645835185;10107.54516046765;10494.160467162455;10522.31604945676;10683.893161068601;10774.622888198875;9285.6285748572;9465.290103242422;9527.761805954906;9533.094356036165;9756.219502439646;8666.480011199348;8992.965808932056;9328.39365441244;9415.435073895589;9505.714828555154;8274.958625206835;8390.916090839013;8395.160483951557;8395.160483951557;8422.620982055945;5908;6048.758049678139;6074.058520929263;6119.143319935123;6197.225346831646;6085.269767627913;6086.452219300284;6095.878082438415;6220.160278362544;6348.365163483615 11:37:04 INFO - PROCESS | 1637 | |4;prototype.html;371.1926559732582;380.76202373516554;398.6392314954965;399.04627939225276;399.17171868373117;4689.648276379273;4861.031282330658;5364.329458817648;5537.667739935601;5678.772849086032;5360.383555891074;5374.381946076202;5388.865278368043;5418.674879507233;5552.4169962154;4004.819783109767;4394.516603173648;4547.818087276513;4565.246734288855;4670.594643758467;3918.119302734689;4408.162449134654;4422.955770442302;4546.613537849286;4572.862814115582;3478.982605086971;4490;4547.045120524694;4555.362249285103;4556.453225612914;4270.441288929518;4295.468280963582;4506.414166158394;4517.683762136652;4518.231900576893;3236.110069730843;3756.793376364299;3983.24318379507;4171.037514361338;4176.937345939813;4195.622393984556;4602.884927876797;4717.2452407614965;4989.625778066623;5193.714345710984;4542.4776150742755;4576.3821884045565;4824.565789079;5006.3241462402475;5145.536901678867;5181.305721025084;5292.153255479144;5370.3287089113865;5839.795607153772;5843.678597677169;3909.885682580479;4547;4549.499555048942;4568.817247310104;4595.816167353302;5128.974355128201;5130.4356520782685;5705.258316418881;5753.798617048383;5885.646861188342;4378.905273681579;4456.532064133284;4654.255319148988;4664.836730714409;4674.415698037746;4267.103908179255;4733.455652599995;4746.762661866893;4748.857534273978;4783.7129772213775;4278.294081476522;4947.406311242602;5076;5142.922856157161;5203.401608815035;5695.231143795535;5701.289613116049;5735.795482948628;5735.942640573624;5821.446962538636;1408.457743768636;1525.4677235788538;1636.8281330460368;1652.4629495413799;1694.2629955969194;1071.7159952612565;1155.485808815086;1176.7175877789268;1183.7869183546873;1244.4213440749913;4659.720416775004;5105.770240339194;5386;5390.407055223922;5396.0843900415575;5414.615865084323;5587.944120558824;6003.039513677878;6006.969965150146;6012.549058820606;4687.492191677505;4771.568529441223;4943.237106714282;5079.644424890296;5381.48875856793;2722.9591556126675;2723.5506141486435;3095;3101.2712012677002;3142.004826934867;5249.973750131225;5471.562275017989;5908.977232048754;5946.256717910262;6128.559788449711;4891.703698519895;5582.701319983671;5820.156077368758;6042;6104.969475152596;5480.862978425492;5659.320881494247;6043.7884674036195;6135.753930389453;6202.247640177808;5154.597965342856;5658.377578466448;5877.619564076555;5939.049752039653;6033.818985430444;4927.531884470969;5096;5206.880520688071;5689.402612725769;5951.548102100684;5190.169572868323;6010.759569617341;6055.788047418319;6098.926365035909;6197.132401463708;4373.846915357947;4622.930656040229;4638.281066434708;4643.955110100335;4648;5359.571234301325;5584.748686309044;5653.869226154703;5889.734961926638;5963.970180149245;5200.97399513;5526.696031718133;5766.798162064308;5786.971065144648;5799.449052340021;4979.402471703419;5021.899562008666;5380.735527151038;5729.656220626609;5829.7085145743395;5491.313585801775;5590.316648008898;5612.242347283228;5632.324121105494;5664.714465609854;5476.808311709071;5546.667199968015;5763.7982670606325;5778.191053252632;5847.532197424111;4855.223164293696;5264.815731449381;5601.215829783752;5664.49019588239;5679.9148012779015 11:37:04 INFO - PROCESS | 1637 | |5;yui.html;749.842533068056;775.3487070860476;799.4643588795507;879.8108406692561;884.5311984648139;15324;18082.457526274215;18978.810211897893;19153.61692766144;19405.611887762272;17961.820381796177;18614.813851861476;18633.068346582684;18759.624807503842;18876.056197190155;704;818.664347617477;1002.9297949143545;1025.3232866308238;1026.2354545863304;990.1039559198937;1016.938983660979;1018.4195008844962;1028.408665017614;1030.7114008077713;835.6239692138562;1069.4438891776272;1077.3266708307308;1077.9407132607703;1080.2546243092281;1052.952617132231;1058.7035630023568;1073.8926107389238;1074.5379486820664;1074.8871368506273;789.3566743104352;795.0737390939548;795.4352409789057;796.4066770256138;796.7888509544975;14362.425502979868;17048.318067277294;17251.30994760208;17557.209925553252;17711;14666.413343466249;14737;14752.926235368861;14780.926095369561;14933.10401375921;11898.46456909441;11929;12015.639530814089;12111;12139.15025948184;3866.7679939203736;3883.10688541637;3898.7075969302414;4035.535913369971;4037.212743515016;123942.14086788795;127239;127298;128055;129499;10532.210084243712;11446.942765286287;11593.88406115928;11664.358460284766;11669.533218671328;22822.543549128924;23155.39525997311;23641.881790590938;23681.881590591936;23774.64338034931;8311.33509319253;8336.374771892133;8408.579571021424;8521.701740439055;8533.53065581399;4607.44710634726;4611.815527378934;4675.859724208211;4781.091592597395;4899.265110233494;3246.8701251949656;3750.3999360102252;3770.472133901256;3788.299164654548;3799.4870692456166;586.9295684517886;622.343427683789;694.3160986428364;702.2626242445411;705.8870580707062;671.1878626861552;681.8295426143465;695.9617221052789;701.4863175441029;736.8857827036818;10625.570616789959;10853.34879907208;10908.672739817817;11262.774744505228;11385.08919286455;682.4676752133344;724.9093863267092;741.9443541734391;745.3552676934413;763.6105586151103;4439.334099884978;4882.5605695486865;5155.252488389237;5265.447128051499;5300.416954135042;109900;111715;113192.43403783093;114127.42936285265;117286;97293.5135324333;99781;100099;102321.48839255905;102360.48819755853;84859.15140848512;85479.14520854712;85869;87219.20294383743;87517.68723469153;9898.714316409276;10061.496925153859;10169;10197.84703229467;10206.897931020892;44297.55702443063;54941.88360799433;56176.62046982838;57456.71271643615;57581.424185757605;61504.384956149865;66355;70638.88083357508;70734;72131.63934180296;11618.30290182558;11639.5926142588;11777.09775809847;11818.46816893259;12421.378931053592;13061.21632702041;13658.931705341409;13697.657558561115;13749.31253437304;14316.642083947985;12073.27560346382;12485.438155283216;12691;12710.300933448749;13142.80285795733;11964.102692297938;12300;12435.37823108859;12567.434465448894;12747.043971701974;11804.527818887493;12250.877491224974;12410.379481026093;12683.80974285405;12889.09776315649;9053.211137052485;9278;9309.906900930904;9454.810903782023;9594.616215351307;8142.679336389338;9018.188363047357;9427.905720942703;9489.905100948901;10538.420386878795 11:37:04 INFO - PROCESS | 1637 | __end_tp_report 11:37:04 INFO - PROCESS | 1637 | __start_cc_report 11:37:04 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,398 11:37:04 INFO - PROCESS | 1637 | __end_cc_report 11:37:04 INFO - PROCESS | 1637 | __startTimestamp1495651024975__endTimestamp 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | ------- Summary: start ------- 11:37:04 INFO - PROCESS | 1637 | Number of tests: 6 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:6161.28 Median:6332.78 stddev:697.88 (11.0%) stddev-sans-first:658.81 11:37:04 INFO - PROCESS | 1637 | Values: 3149.4 3181.8 3688.3 3693.5 3703.7 5504.4 5558.9 6145.9 6241.8 6274.2 6227.9 6234.0 6245.4 6248.6 6257.6 5652.8 5673.3 5731.6 5761.5 5796.3 4949.4 5856.2 5872.5 5880.3 5919.3 5566.3 5591.2 5780.4 5822.5 5833.5 5692.4 5711.2 5722.4 5735.8 5777.0 5548.9 5705.1 5705.2 5738.6 5738.6 6061.1 6193.7 6256.3 6283.4 6295.2 6373.0 6537.0 6565.1 6601.7 6610.0 6732.5 6797.9 6800.0 6807.5 6818.3 6288.8 6296.7 6316.2 6318.2 6320.6 6587.7 6620.1 6655.6 6781.2 6868.7 6593.8 6600.5 6616.3 6616.4 6640.7 5375.6 6723.0 6748.5 6771.7 6774.9 6356.4 6414.4 6442.8 6454.2 6644.5 6539.6 6551.2 6589.0 6609.3 6610.9 5214.2 5238.6 5312.8 5360.9 5366.7 4748.2 4794.9 4805.8 4828.2 4835.7 6233.7 6314.1 6318.8 6329.8 6335.9 6516.2 6639.0 6653.6 6722.5 6732.9 6324.8 6328.6 6332.8 6344.7 6344.9 5963.6 5981.0 5984.7 5996.9 5998.0 5390.5 6210.4 6615.8 6698.1 6791.6 6758.5 6800.6 6803.1 6814.6 6817.8 6863.4 6929.8 6965.3 6977.3 6989.2 6326.3 6352.1 6550.9 6559.1 6705.3 6764.3 6778.2 6779.2 6794.0 6811.6 6497.0 6503.8 6519.2 6531.9 6544.0 5455.9 5939.7 6427.1 6536.6 6583.9 6301.7 6341.3 6362.1 6424.6 6425.7 6533.5 6555.6 6558.6 6560.2 6583.8 4928.3 6179.5 6223.2 6324.1 6442.1 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:56924.89 Median:31815.26 stddev:117090.64 (368.0%) stddev-sans-first:117343.20 11:37:04 INFO - PROCESS | 1637 | Values: 769.3 771.2 846.8 976.8 976.8 39061.8 40161.0 41486.0 41765.0 41827.0 40003.2 40145.6 40234.2 40255.0 40505.0 8193.3 8339.2 11198.8 11479.4 11633.1 11171.5 11198.8 11247.8 11272.0 11291.8 9310.7 9360.3 11192.8 11201.6 11224.5 9877.7 11214.7 11224.0 11247.7 11249.0 11065.7 11134.9 11180.7 11183.2 11196.8 27438.7 35195.6 35442.0 35487.8 35502.0 3850.6 3896.0 3901.5 3909.9 3927.4 26110.0 26424.7 26455.6 26500.6 27003.8 3158.2 3211.8 3213.5 3265.8 3335.1 323629.0 326946.0 347663.0 355088.0 355477.0 36097.0 38023.8 46206.5 46422.8 46472.8 35468.0 39592.8 48520.5 48634.3 48677.3 14398.0 14511.9 14556.8 14604.3 14610.4 12644.0 12713.1 12730.7 12748.4 12753.7 11992.5 12216.3 12298.5 12320.1 12379.7 5856.7 5871.2 5887.1 5903.6 5915.2 1016.4 1062.4 1151.5 1167.4 1188.2 26982.3 27109.8 27196.3 27298.7 27481.0 1047.3 1110.2 1263.2 1298.5 1333.8 13662.7 13909.0 14187.9 14244.7 14560.1 501572.0 503519.0 504026.0 504889.0 506190.0 58243.7 58370.0 58440.7 58456.0 58566.7 1505.3 1529.3 1547.3 1553.5 1560.9 25292.0 26542.7 26919.7 26961.5 27138.0 47572.5 47617.8 47756.5 47762.5 47938.6 456334.0 459147.4 459552.0 460941.0 461123.0 27377.6 27780.6 32570.8 32731.0 32857.8 44982.8 45157.1 45201.5 45861.8 46663.8 31368.8 31537.9 31673.5 31724.0 32257.7 30770.8 31114.8 31433.0 31690.4 31952.4 36532.6 36819.3 37105.8 37171.3 37292.3 25869.6 31271.4 31404.5 31679.2 31906.5 21706.5 22941.2 24001.3 24996.5 25497.9 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:43445.03 Median:23422.09 stddev:127076.89 (542.6%) stddev-sans-first:127392.32 11:37:04 INFO - PROCESS | 1637 | Values: 426.4 439.9 444.9 449.8 455.2 21238.9 22261.8 22694.0 23195.4 23544.8 19003.7 22992.0 23299.4 24259.4 24425.5 6914.5 7015.1 7121.0 7212.4 7289.5 7054.3 7060.3 7130.9 7680.5 8025.4 8045.1 8122.2 8123.8 8129.4 8159.0 7772.7 7993.2 8017.3 8019.8 8046.7 7516.0 7740.1 7796.9 7811.0 7824.8 26121.0 26788.9 26824.7 27339.2 27624.2 3025.3 3046.7 3074.7 3137.2 3148.7 15886.3 16202.4 16739.9 16825.7 17153.0 2100.3 2119.1 2159.6 2188.1 2213.2 130213.0 162683.2 166567.0 168867.0 168956.2 20559.7 21926.7 22174.9 22300.7 22539.9 33250.2 33380.0 34057.5 34101.3 34116.0 10800.4 10965.6 11147.7 11203.3 11509.0 9256.4 9291.1 9311.1 9482.0 9540.0 7860.5 9558.1 9613.0 9838.5 9846.1 3914.5 4080.7 4280.2 4398.3 4421.2 862.6 901.5 902.8 965.0 967.7 21972.2 22326.0 22435.7 22539.0 22839.5 775.8 952.1 952.8 976.1 992.7 8331.3 10847.7 11261.2 11408.6 11432.0 695696.0 764763.0 771468.0 791898.0 792368.0 130160.0 130617.0 138225.9 146619.3 161122.4 1412.7 1428.3 1450.3 1480.7 1494.2 19828.8 20521.0 20779.0 20847.0 21169.7 46824.3 56029.0 59579.0 63191.7 64350.4 77778.0 82281.6 84414.6 84768.0 85141.9 10825.5 10939.9 11162.2 11395.9 11433.8 12353.7 12384.3 12679.1 12769.6 12816.2 10918.8 11067.2 11251.9 11268.7 11327.8 10279.5 10344.9 10359.7 10374.7 10435.4 11109.0 11187.5 11306.8 11566.5 11632.7 11517.1 11526.6 11530.3 11684.1 11874.7 10967.7 11003.0 11250.8 11333.5 11394.5 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6914.65 Median:2497.38 stddev:4273.84 (171.1%) stddev-sans-first:4266.30 11:37:04 INFO - PROCESS | 1637 | Values: 1478.5 1627.7 1655.5 1663.3 1667.1 11090.4 11673.0 11778.2 11808.8 12071.5 11528.9 11650.7 11738.8 11758.2 11759.6 2229.3 2325.6 2486.0 2492.2 2507.0 2408.3 2472.2 2482.5 2495.3 2499.4 2406.2 2446.9 2453.6 2477.7 2478.9 2370.9 2416.0 2416.5 2416.9 2461.3 2431.2 2449.1 2450.2 2464.5 2475.0 11350.8 11494.9 11541.1 11594.8 11621.7 6042.2 6105.8 6199.9 6200.7 6206.7 3160.7 3677.2 3775.4 3775.5 3820.0 1250.5 1261.4 1285.3 1309.9 1315.7 14142.9 14366.1 15193.9 15926.4 16075.0 4817.1 4817.6 4985.8 5002.3 5048.7 9249.6 9798.6 12227.0 12250.1 12335.9 5482.6 5922.3 6145.2 6145.2 6157.8 5386.2 5452.7 5458.7 5496.6 5569.5 4073.8 4112.7 4164.0 4203.2 4215.8 1087.6 1088.4 1090.8 1105.6 1106.5 764.6 938.7 942.4 942.7 979.7 6616.4 6697.7 6808.0 6862.4 7159.5 808.6 958.4 1002.1 1028.9 1040.5 4283.7 4319.9 4336.3 4363.4 4422.4 13686.2 14130.4 14133.2 14261.7 14264.7 13272.9 13816.7 14081.0 14164.9 14286.9 8977.9 9021.1 9206.5 10402.8 10613.7 5837.3 6117.6 6889.9 6979.8 7021.6 11825.2 11896.5 12045.9 12121.5 12170.9 12162.5 12197.0 12197.1 12401.7 12721.9 9494.6 9787.0 9795.0 9796.5 9804.2 10107.5 10494.2 10522.3 10683.9 10774.6 9285.6 9465.3 9527.8 9533.1 9756.2 8666.5 8993.0 9328.4 9415.4 9505.7 8275.0 8390.9 8395.2 8395.2 8422.6 5908.0 6048.8 6074.1 6119.1 6197.2 6085.3 6086.5 6095.9 6220.2 6348.4 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4760.10 Median:5129.71 stddev:1329.68 (25.9%) stddev-sans-first:1291.96 11:37:04 INFO - PROCESS | 1637 | Values: 371.2 380.8 398.6 399.0 399.2 4689.6 4861.0 5364.3 5537.7 5678.8 5360.4 5374.4 5388.9 5418.7 5552.4 4004.8 4394.5 4547.8 4565.2 4670.6 3918.1 4408.2 4423.0 4546.6 4572.9 3479.0 4490.0 4547.0 4555.4 4556.5 4270.4 4295.5 4506.4 4517.7 4518.2 3236.1 3756.8 3983.2 4171.0 4176.9 4195.6 4602.9 4717.2 4989.6 5193.7 4542.5 4576.4 4824.6 5006.3 5145.5 5181.3 5292.2 5370.3 5839.8 5843.7 3909.9 4547.0 4549.5 4568.8 4595.8 5129.0 5130.4 5705.3 5753.8 5885.6 4378.9 4456.5 4654.3 4664.8 4674.4 4267.1 4733.5 4746.8 4748.9 4783.7 4278.3 4947.4 5076.0 5142.9 5203.4 5695.2 5701.3 5735.8 5735.9 5821.4 1408.5 1525.5 1636.8 1652.5 1694.3 1071.7 1155.5 1176.7 1183.8 1244.4 4659.7 5105.8 5386.0 5390.4 5396.1 5414.6 5587.9 6003.0 6007.0 6012.5 4687.5 4771.6 4943.2 5079.6 5381.5 2723.0 2723.6 3095.0 3101.3 3142.0 5250.0 5471.6 5909.0 5946.3 6128.6 4891.7 5582.7 5820.2 6042.0 6105.0 5480.9 5659.3 6043.8 6135.8 6202.2 5154.6 5658.4 5877.6 5939.0 6033.8 4927.5 5096.0 5206.9 5689.4 5951.5 5190.2 6010.8 6055.8 6098.9 6197.1 4373.8 4622.9 4638.3 4644.0 4648.0 5359.6 5584.7 5653.9 5889.7 5964.0 5201.0 5526.7 5766.8 5787.0 5799.4 4979.4 5021.9 5380.7 5729.7 5829.7 5491.3 5590.3 5612.2 5632.3 5664.7 5476.8 5546.7 5763.8 5778.2 5847.5 4855.2 5264.8 5601.2 5664.5 5679.9 11:37:04 INFO - PROCESS | 1637 | 11:37:04 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:22243.30 Median:18927.43 stddev:33295.98 (175.9%) stddev-sans-first:33350.27 11:37:04 INFO - PROCESS | 1637 | Values: 749.8 775.3 799.5 879.8 884.5 15324.0 18082.5 18978.8 19153.6 19405.6 17961.8 18614.8 18633.1 18759.6 18876.1 704.0 818.7 1002.9 1025.3 1026.2 990.1 1016.9 1018.4 1028.4 1030.7 835.6 1069.4 1077.3 1077.9 1080.3 1053.0 1058.7 1073.9 1074.5 1074.9 789.4 795.1 795.4 796.4 796.8 14362.4 17048.3 17251.3 17557.2 17711.0 14666.4 14737.0 14752.9 14780.9 14933.1 11898.5 11929.0 12015.6 12111.0 12139.2 3866.8 3883.1 3898.7 4035.5 4037.2 123942.1 127239.0 127298.0 128055.0 129499.0 10532.2 11446.9 11593.9 11664.4 11669.5 22822.5 23155.4 23641.9 23681.9 23774.6 8311.3 8336.4 8408.6 8521.7 8533.5 4607.4 4611.8 4675.9 4781.1 4899.3 3246.9 3750.4 3770.5 3788.3 3799.5 586.9 622.3 694.3 702.3 705.9 671.2 681.8 696.0 701.5 736.9 10625.6 10853.3 10908.7 11262.8 11385.1 682.5 724.9 741.9 745.4 763.6 4439.3 4882.6 5155.3 5265.4 5300.4 109900.0 111715.0 113192.4 114127.4 117286.0 97293.5 99781.0 100099.0 102321.5 102360.5 84859.2 85479.1 85869.0 87219.2 87517.7 9898.7 10061.5 10169.0 10197.8 10206.9 44297.6 54941.9 56176.6 57456.7 57581.4 61504.4 66355.0 70638.9 70734.0 72131.6 11618.3 11639.6 11777.1 11818.5 12421.4 13061.2 13658.9 13697.7 13749.3 14316.6 12073.3 12485.4 12691.0 12710.3 13142.8 11964.1 12300.0 12435.4 12567.4 12747.0 11804.5 12250.9 12410.4 12683.8 12889.1 9053.2 9278.0 9309.9 9454.8 9594.6 8142.7 9018.2 9427.9 9489.9 10538.4 11:37:04 INFO - PROCESS | 1637 | -------- Summary: end -------- 11:37:04 INFO - PROCESS | 1637 | 11:37:05 INFO - TEST-INFO | 1637: exit 0 11:37:05 INFO - TEST-OK | dromaeo_css | took 1115391ms 11:37:05 INFO - TEST-START | kraken 11:37:05 INFO - operating with platform_type : mac_ 11:37:05 INFO - Initialising browser for kraken test... 11:37:05 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpnIhlo5/profile http://localhost:49220/getInfo.html) 11:37:08 INFO - PROCESS | 1649 | 2017-05-24 11:37:08.267 plugin-container[1651:10096] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 11:37:08 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:37:08 INFO - PROCESS | 1649 | 2017-05-24 11:37:08.268 plugin-container[1651:10096] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 11:37:08 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:37:08 INFO - PROCESS | 1649 | 2017-05-24 11:37:08.268 plugin-container[1651:10096] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:37:08 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 11:37:08 INFO - PROCESS | 1649 | colorDepth:24 11:37:08 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 11:37:08 INFO - PROCESS | 1649 | __metrics 11:37:08 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:37:09 INFO - TEST-INFO | 1649: exit 0 11:37:09 INFO - Browser initialized. 11:37:09 INFO - Running cycle 1/1 for kraken test... 11:37:09 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpnIhlo5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:37:11 INFO - PROCESS | 1652 | 2017-05-24 11:37:11.502 plugin-container[1653:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:37:11 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:37:11 INFO - PROCESS | 1652 | 2017-05-24 11:37:11.502 plugin-container[1653:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880f, name = 'com.apple.CFPasteboardClient' 11:37:11 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:37:11 INFO - PROCESS | 1652 | 2017-05-24 11:37:11.502 plugin-container[1653:10230] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:37:21 INFO - PROCESS | 1652 | RSS: Main: 174342144 11:37:21 INFO - PROCESS | 1652 | 11:37:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection) 11:37:25 INFO - PROCESS | 1652 | RSS: Main: 188039168 11:37:25 INFO - PROCESS | 1652 | 11:37:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-dft) 11:37:28 INFO - PROCESS | 1652 | RSS: Main: 195948544 11:37:28 INFO - PROCESS | 1652 | 11:37:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-fft) 11:37:32 INFO - PROCESS | 1652 | RSS: Main: 199561216 11:37:32 INFO - PROCESS | 1652 | 11:37:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator) 11:37:34 INFO - PROCESS | 1652 | RSS: Main: 200032256 11:37:34 INFO - PROCESS | 1652 | 11:37:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:37:37 INFO - PROCESS | 1652 | RSS: Main: 201490432 11:37:37 INFO - PROCESS | 1652 | 11:37:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom) 11:37:40 INFO - PROCESS | 1652 | RSS: Main: 205320192 11:37:40 INFO - PROCESS | 1652 | 11:37:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate) 11:37:44 INFO - PROCESS | 1652 | RSS: Main: 206069760 11:37:44 INFO - PROCESS | 1652 | 11:37:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial) 11:37:48 INFO - PROCESS | 1652 | RSS: Main: 205291520 11:37:48 INFO - PROCESS | 1652 | 11:37:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:37:50 INFO - PROCESS | 1652 | RSS: Main: 204550144 11:37:50 INFO - PROCESS | 1652 | 11:37:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:37:53 INFO - PROCESS | 1652 | RSS: Main: 204644352 11:37:53 INFO - PROCESS | 1652 | 11:37:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:37:56 INFO - PROCESS | 1652 | RSS: Main: 204570624 11:37:56 INFO - PROCESS | 1652 | 11:37:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:37:59 INFO - PROCESS | 1652 | RSS: Main: 204972032 11:37:59 INFO - PROCESS | 1652 | 11:38:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:38:03 INFO - PROCESS | 1652 | RSS: Main: 205074432 11:38:03 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49220/tests/kraken/driver.html?testName=ai-astar) 11:38:05 INFO - PROCESS | 1652 | RSS: Main: 207249408 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | __start_tp_report 11:38:05 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 11:38:05 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 11:38:05 INFO - PROCESS | 1652 | |i|pagename|runs| 11:38:05 INFO - PROCESS | 1652 | |0;ai-astar;105;119;118;120;119;120;119;119;119;123 11:38:05 INFO - PROCESS | 1652 | |1;audio-beat-detection;142;112;110;147;110;117;110;110;128;108 11:38:05 INFO - PROCESS | 1652 | |2;audio-dft;197;198;202;206;197;197;194;195;203;199 11:38:05 INFO - PROCESS | 1652 | |3;audio-fft;83;59;68;112;87;87;64;63;72;59 11:38:05 INFO - PROCESS | 1652 | |4;audio-oscillator;87;69;83;71;72;70;81;71;69;82 11:38:05 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;92;93;92;93;93;92;92;107;93;110 11:38:05 INFO - PROCESS | 1652 | |6;imaging-darkroom;99;99;99;98;99;98;99;99;98;106 11:38:05 INFO - PROCESS | 1652 | |7;imaging-desaturate;88;93;131;89;147;128;78;115;125;74 11:38:05 INFO - PROCESS | 1652 | |8;json-parse-financial;58;70;94;94;57;86;93;107;58;92 11:38:05 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;56;88;91;61;81;59;57;63;88;85 11:38:05 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;80;83;85;85;86;83;93;105;84;88 11:38:05 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;123;145;167;141;133;150;146;155;178;155 11:38:05 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;201;229;166;164;182;152;228;191;231;220 11:38:05 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;67;66;65;76;62;63;66;64;65 11:38:05 INFO - PROCESS | 1652 | __end_tp_report 11:38:05 INFO - PROCESS | 1652 | __start_cc_report 11:38:05 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,984 11:38:05 INFO - PROCESS | 1652 | __end_cc_report 11:38:05 INFO - PROCESS | 1652 | __startTimestamp1495651085567__endTimestamp 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | ------- Summary: start ------- 11:38:05 INFO - PROCESS | 1652 | Number of tests: 14 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:118.10 Median:119.00 stddev:4.79 (4.0%) stddev-sans-first:1.42 11:38:05 INFO - PROCESS | 1652 | Values: 105.0 119.0 118.0 120.0 119.0 120.0 119.0 119.0 119.0 123.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:119.40 Median:114.50 stddev:14.49 (12.7%) stddev-sans-first:12.86 11:38:05 INFO - PROCESS | 1652 | Values: 142.0 112.0 110.0 147.0 110.0 117.0 110.0 110.0 128.0 108.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:198.80 Median:198.50 stddev:3.77 (1.9%) stddev-sans-first:3.94 11:38:05 INFO - PROCESS | 1652 | Values: 197.0 198.0 202.0 206.0 197.0 197.0 194.0 195.0 203.0 199.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:75.40 Median:70.00 stddev:16.85 (24.1%) stddev-sans-first:17.64 11:38:05 INFO - PROCESS | 1652 | Values: 83.0 59.0 68.0 112.0 87.0 87.0 64.0 63.0 72.0 59.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.50 stddev:6.90 (9.0%) stddev-sans-first:5.93 11:38:05 INFO - PROCESS | 1652 | Values: 87.0 69.0 83.0 71.0 72.0 70.0 81.0 71.0 69.0 82.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:92.50 stddev:6.80 (7.4%) stddev-sans-first:7.08 11:38:05 INFO - PROCESS | 1652 | Values: 92.0 93.0 92.0 93.0 93.0 92.0 92.0 107.0 93.0 110.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:99.00 stddev:2.37 (2.4%) stddev-sans-first:2.51 11:38:05 INFO - PROCESS | 1652 | Values: 99.0 99.0 99.0 98.0 99.0 98.0 99.0 99.0 98.0 106.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:106.80 Median:76.00 stddev:25.42 (33.4%) stddev-sans-first:26.04 11:38:05 INFO - PROCESS | 1652 | Values: 88.0 93.0 131.0 89.0 147.0 128.0 78.0 115.0 125.0 74.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:80.90 Median:89.00 stddev:18.44 (20.7%) stddev-sans-first:17.59 11:38:05 INFO - PROCESS | 1652 | Values: 58.0 70.0 94.0 94.0 57.0 86.0 93.0 107.0 58.0 92.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:83.00 stddev:14.78 (17.8%) stddev-sans-first:14.36 11:38:05 INFO - PROCESS | 1652 | Values: 56.0 88.0 91.0 61.0 81.0 59.0 57.0 63.0 88.0 85.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:85.00 stddev:7.15 (8.4%) stddev-sans-first:7.09 11:38:05 INFO - PROCESS | 1652 | Values: 80.0 83.0 85.0 85.0 86.0 83.0 93.0 105.0 84.0 88.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:152.50 stddev:15.84 (10.4%) stddev-sans-first:13.65 11:38:05 INFO - PROCESS | 1652 | Values: 123.0 145.0 167.0 141.0 133.0 150.0 146.0 155.0 178.0 155.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.40 Median:210.50 stddev:29.85 (14.2%) stddev-sans-first:31.61 11:38:05 INFO - PROCESS | 1652 | Values: 201.0 229.0 166.0 164.0 182.0 152.0 228.0 191.0 231.0 220.0 11:38:05 INFO - PROCESS | 1652 | 11:38:05 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:3.83 (5.8%) stddev-sans-first:4.06 11:38:05 INFO - PROCESS | 1652 | Values: 66.0 67.0 66.0 65.0 76.0 62.0 63.0 66.0 64.0 65.0 11:38:05 INFO - PROCESS | 1652 | -------- Summary: end -------- 11:38:05 INFO - PROCESS | 1652 | 11:38:06 INFO - TEST-INFO | 1652: exit 0 11:38:06 INFO - TEST-OK | kraken | took 60950ms 11:38:06 INFO - SUITE-END | took 1176s 11:38:06 INFO - Completed test suite (00:19:36) 11:38:06 INFO - javascript benchmark 11:38:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7963.2573915746525, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.4016136933983, 3181.822725591531, 3688.2992231476032, 3693.4644476550884, 3703.6851867591254, 5504.366997795255, 5558.888822223563, 6145.924463218941, 6241.8127456176335, 6274.215723034621, 6227.875442491148, 6234.0, 6245.375462453752, 6248.562600617958, 6257.624542527452, 5652.830415087554, 5673.319201695792, 5731.570132240078, 5761.4814666679995, 5796.275465566804, 4949.381327334083, 5856.180134781134, 5872.530197584204, 5880.323762767293, 5919.289685237777, 5566.276384070065, 5591.161325801122, 5780.393058728836, 5822.505087067583, 5833.533317334603, 5692.430756924296, 5711.171880077365, 5722.399148089432, 5735.770569177228, 5776.97111514444, 5548.944510554883, 5705.144228365737, 5705.2012718219485, 5738.598298119092, 5738.626989245686, 6061.121137435091, 6193.6903154842075, 6256.280527739323, 6283.4344908957955, 6295.213098362705, 6372.98032314832, 6537.0, 6565.113709649232, 6601.6699165041555, 6609.966950165219, 6732.528722989393, 6797.932020679828, 6800.0, 6807.523473356867, 6818.28408017156, 6288.779892703734, 6296.685165741695, 6316.1788967434395, 6318.241810982666, 6320.5891617044745, 6587.670616469253, 6620.139381880324, 6655.600663960178, 6781.186257649114, 6868.694947959872, 6593.8351541210895, 6600.53796234259, 6616.305287944697, 6616.4045235929, 6640.734370625121, 5375.596830237748, 6723.039983296807, 6748.493862960199, 6771.72913083481, 6774.898376524356, 6356.364363563606, 6414.422701956846, 6442.8389290268115, 6454.19322584677, 6644.534882558174, 6539.607623542603, 6551.213854337415, 6589.044588534635, 6609.339066093448, 6610.933890661128, 5214.191800270964, 5238.633295669343, 5312.76092575835, 5360.865978350573, 5366.651167674088, 4748.240281554935, 4794.880127996828, 4805.831795887128, 4828.179209534387, 4835.685680430761, 6233.688315584202, 6314.0528920661345, 6318.842028949313, 6329.778457753957, 6335.904961425582, 6516.152900122954, 6639.0, 6653.6340501271925, 6722.462203023746, 6732.932670673328, 6324.778632747832, 6328.588641738273, 6332.778352757631, 6344.746210151635, 6344.873102538016, 5963.612365196248, 5981.043033114681, 5984.73068711909, 5996.940030599638, 5998.0, 5390.541803946709, 6210.4100110489435, 6615.834604134935, 6698.062271282024, 6791.592504449749, 6758.4931130166415, 6800.557963732342, 6803.081583986099, 6814.591124532544, 6817.761378351932, 6863.416609588241, 6929.792106236821, 6965.303469652994, 6977.2673869243, 6989.231184569795, 6326.272478665012, 6352.0789485525265, 6550.868982620225, 6559.147310849559, 6705.2959439258175, 6764.323567643196, 6778.2205046420295, 6779.15260592426, 6794.0, 6811.625360605214, 6497.0, 6503.772367967098, 6519.152510173837, 6531.934680653133, 6543.967280163569, 5455.890882182413, 5939.693267481147, 6427.132337134427, 6536.575122617205, 6583.868322633616, 6301.653409062362, 6341.302456729848, 6362.141110949962, 6424.614523128627, 6425.742970281323, 6533.542652014314, 6555.639439830855, 6558.606483610999, 6560.245571759308, 6583.769568065095, 4928.334674818853, 6179.474744646755, 6223.159873417031, 6324.114623952558, 6442.098106265033], "value": 6117.476767837231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.3460558525251, 771.2056581720827, 846.767139036765, 976.8144052629996, 976.8485884687872, 39061.828145155654, 40161.0, 41486.0, 41765.0, 41827.0, 40003.19993600126, 40145.59854401455, 40234.19531609366, 40255.0, 40504.987375315555, 8193.30356919661, 8339.20777526134, 11198.832017519744, 11479.368634725086, 11633.127515436334, 11171.49728262226, 11198.776024479506, 11247.83128253077, 11272.0, 11291.830622540627, 9310.674126405576, 9360.344775865693, 11192.776144477106, 11201.551937922473, 11224.49489772958, 9877.703668889944, 11214.66356009321, 11224.045956093702, 11247.718807029849, 11248.987591116756, 11065.723356916062, 11134.888651113466, 11180.664580062612, 11183.161262905232, 11196.832047519294, 27438.725612743816, 35195.64804351949, 35442.0, 35487.82256088729, 35502.0, 3850.595687452805, 3895.9805200973815, 3901.51231096113, 3909.9218015639813, 3927.351986922183, 26110.0, 26424.73575264242, 26455.603165952525, 26500.60249096265, 27003.78482968271, 3158.2262345725217, 3211.759118066131, 3213.53403756454, 3265.804051756902, 3335.1495368681076, 323629.0, 326946.0, 347663.0, 355088.0, 355477.0, 36097.0, 38023.809880950415, 46206.537934620894, 46422.767886160356, 46472.7676361616, 35468.0, 39592.802035990215, 48520.544383668544, 48634.27048594274, 48677.26984095242, 14398.0, 14511.854881451261, 14556.781648275284, 14604.269786510844, 14610.415583376758, 12644.0, 12713.110082294335, 12730.745385092432, 12748.426320815586, 12753.681157971125, 11992.460339284753, 12216.26702397859, 12298.508059677692, 12320.075994300463, 12379.6905077372, 5856.677882716406, 5871.207387002785, 5887.146363777314, 5903.586748927534, 5915.201447804578, 1016.3596933931724, 1062.4262898034974, 1151.5163631274884, 1167.4104577188466, 1188.2454641302836, 26982.32544186372, 27109.78005989735, 27196.32009199786, 27298.727012730014, 27481.0, 1047.2773786087575, 1110.2228440091806, 1263.216805580546, 1298.532528289815, 1333.7865941449322, 13662.658433539042, 13909.0, 14187.929060354632, 14244.715105697827, 14560.053596516193, 501572.0, 503519.0, 504026.0, 504889.0, 506190.0, 58243.70878145667, 58370.0, 58440.7077964616, 58456.0, 58566.707166464745, 1505.3226048278318, 1529.258309719785, 1547.3423794887353, 1553.4873491747696, 1560.9297581608628, 25292.0, 26542.734572654026, 26919.730802691724, 26961.517116557992, 27138.0, 47572.52427475681, 47617.761911190224, 47756.5224347766, 47762.52237477581, 47938.56184314476, 456334.0, 459147.40852591046, 459552.0, 460941.0, 461123.0, 27377.589336159574, 27780.583291251052, 32570.83714581412, 32731.0, 32857.835710821295, 44982.77508612436, 45157.096858061996, 45201.54798452105, 45861.77069114633, 46663.76668116773, 31368.843155784074, 31537.896173633813, 31673.5248971261, 31724.0, 32257.67742322547, 30770.846145770025, 31114.844425777726, 31433.0, 31690.366192675556, 31952.36095278128, 36532.634673653985, 36819.263614728094, 37105.77365358087, 37171.256574868894, 37292.254154916205, 25869.611955820303, 31271.374572507968, 31404.528932066496, 31679.20801979969, 31906.52140217852, 21706.457338566663, 22941.197058103553, 24001.270118478267, 24996.500069998863, 25497.872510637328], "value": 18977.005999644014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.41793951256545, 439.93840862279285, 444.94438195225587, 449.84480354277764, 455.2283878825391, 21238.89380553097, 22261.77738222619, 22693.999999999978, 23195.420114497145, 23544.76455235447, 19003.714944275845, 22992.0, 23299.417514562145, 24259.39351516213, 24425.511489770237, 6914.5159838811305, 7015.123109611286, 7121.0, 7212.423006159521, 7289.453291003196, 7054.29457054293, 7060.293970602925, 7130.8573828523395, 7680.462367634268, 8025.438219324649, 8045.115037345888, 8122.18778122189, 8123.837523249532, 8129.430939834214, 8158.959205203966, 7772.689092436324, 7993.240642138988, 8017.278444939983, 8019.799505012363, 8046.718364857261, 7516.0, 7740.148583655793, 7796.947412099407, 7810.960945195295, 7824.804379890491, 26121.0, 26788.86605566979, 26824.73175268242, 27339.179824605293, 27624.171274861787, 3025.273934255763, 3046.7105624965593, 3074.723274905269, 3137.2313783123054, 3148.7008734170217, 15886.285117169755, 16202.432914848041, 16739.916300418543, 16825.66348673032, 17152.970821750736, 2100.2649072824543, 2119.13115622594, 2159.5680863827297, 2188.070070220166, 2213.2143089203423, 130213.0, 162683.1865840675, 166567.0, 168867.0, 168956.15521922434, 20559.738428772824, 21926.671099933516, 22174.889125554593, 22300.66549001766, 22539.87300634962, 33250.16874578106, 33380.0, 34057.48913766295, 34101.317973640886, 34116.0, 10800.351978881292, 10965.61620343284, 11147.72130696739, 11203.271787333797, 11508.964193222482, 9256.444613323221, 9291.117343852324, 9311.115444032806, 9481.952590237142, 9539.950605433396, 7860.489068210548, 9558.091981261769, 9613.0, 9838.458884761263, 9846.113849753414, 3914.5106861642294, 4080.6939479539155, 4280.229558679405, 4398.3402489626815, 4421.182081314967, 862.6333808131519, 901.5492253873064, 902.8058967322057, 965.0, 967.7483854197818, 21972.230971915906, 22326.0, 22435.663465048037, 22539.0, 22839.543209135725, 775.848709501653, 952.0578020837553, 952.8142012307673, 976.1019861727086, 992.7220378294089, 8331.33349332052, 10847.67456976292, 11261.155413343868, 11408.600698975495, 11431.971122599007, 695696.0, 764763.0, 771468.0, 791898.0, 792368.0, 130160.0, 130617.0, 138225.9266111009, 146619.2669036648, 161122.38877611308, 1412.7245187188605, 1428.2644438114587, 1450.2676148544917, 1480.696457226266, 1494.2230040378943, 19828.801711982695, 20520.973951302673, 20779.0, 20847.0, 21169.6824547635, 46824.297635536175, 56029.0, 59579.0, 63191.6840415795, 64350.35649643631, 77778.0, 82281.58859205665, 84414.57792711243, 84768.0, 85141.87145321169, 10825.458727063773, 10939.945300273448, 11162.218644694793, 11395.943020285176, 11433.771324573629, 12353.71131032695, 12384.31886246229, 12679.112462127932, 12769.61691149267, 12816.231026138461, 10918.836217456586, 11067.225294229327, 11251.887481125084, 11268.661940141808, 11327.773444531229, 10279.53742081623, 10344.896551034393, 10359.689209323733, 10374.740631484274, 10435.426051567236, 11109.00018998294, 11187.496562654538, 11306.83039754388, 11566.537338506365, 11632.651020469399, 11517.136214783757, 11526.59656912004, 11530.250533715282, 11684.1236907234, 11874.703132421413, 10967.725806854893, 11003.0, 11250.831237531607, 11333.546658133582, 11394.48724807369], "value": 11977.939636495496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1478.534261707562, 1627.7151498487763, 1655.4619748581713, 1663.3429795230884, 1667.1164282930047, 11090.390028548427, 11673.0, 11778.175527713052, 11808.822867656992, 12071.51713931444, 11528.884711152887, 11650.650480485578, 11738.7652246955, 11758.23571467854, 11759.647210583675, 2229.2754854672257, 2325.5697695926306, 2485.9751402485927, 2492.1775813981344, 2506.987465062672, 2408.2654790288957, 2472.196536125757, 2482.478679477303, 2495.3137887081016, 2499.4376265340243, 2406.181898154627, 2446.9388265293333, 2453.6442215878687, 2477.665515155458, 2478.8512689238614, 2370.940726481852, 2416.0, 2416.5287768885073, 2416.891239894209, 2461.310832966772, 2431.234161239204, 2449.0571130114854, 2450.2036838027534, 2464.4578192797558, 2474.9628755568683, 11350.829737553944, 11494.942525287404, 11541.076713862954, 11594.76810463786, 11621.6513504595, 6042.154098426224, 6105.786297479612, 6199.907001394983, 6200.720967556471, 6206.689665516751, 3160.652328243891, 3677.191017976041, 3775.4336849472525, 3775.4903088083033, 3820.0449887528116, 1250.4783725888785, 1261.369315342329, 1285.254552359629, 1309.9410526526328, 1315.7105436803888, 14142.939279554075, 14366.066205696596, 15193.924030379778, 15926.362945482168, 16075.0, 4817.108834865561, 4817.602895160434, 4985.800567977313, 5002.349694539686, 5048.747562621854, 9249.583768730423, 9798.608055677692, 12226.960708339891, 12250.081243906743, 12335.876641233652, 5482.5613950883835, 5922.312141039783, 6145.201123853871, 6145.231846019247, 6157.753689852445, 5386.245925570423, 5452.727363631802, 5458.699771512525, 5496.615236933456, 5569.498745112959, 4073.839559690213, 4112.650424713873, 4164.03534507193, 4203.243416185116, 4215.810288536962, 1087.5867170475326, 1088.3687461272443, 1090.841827934945, 1105.6179775280898, 1106.4578356605205, 764.58712295361, 938.7230766923774, 942.4486675295029, 942.7266092833139, 979.6816034788723, 6616.437602803816, 6697.665116744143, 6807.965960170168, 6862.382385585322, 7159.534630249017, 808.640155130961, 958.4057884111895, 1002.1231422505308, 1028.89711028898, 1040.5213601743283, 4283.721558098714, 4319.892002699895, 4336.3278691802825, 4363.38912552243, 4422.447194100738, 13686.178829270275, 14130.364133613806, 14133.15201087938, 14261.714765704628, 14264.714705705828, 13272.867271327163, 13816.654583635489, 14081.014328997077, 14164.929175354056, 14286.928565357106, 8977.910220897707, 9021.052789457144, 9206.493642849708, 10402.791944161074, 10613.73465663349, 5837.328707198557, 6117.571769976237, 6889.897616381355, 6979.790606281819, 7021.613811240431, 11825.231359961575, 11896.524139034687, 12045.939770301093, 12121.515139394325, 12170.939145304217, 12162.452689628812, 12197.02423806111, 12197.085218608463, 12401.689957751129, 12721.93639031799, 9494.620215191315, 9786.951065244913, 9795.0, 9796.461194634363, 9804.166645835185, 10107.54516046765, 10494.160467162455, 10522.31604945676, 10683.893161068601, 10774.622888198875, 9285.6285748572, 9465.290103242422, 9527.761805954906, 9533.094356036165, 9756.219502439646, 8666.480011199348, 8992.965808932056, 9328.39365441244, 9415.435073895589, 9505.714828555154, 8274.958625206835, 8390.916090839013, 8395.160483951557, 8395.160483951557, 8422.620982055945, 5908.0, 6048.758049678139, 6074.058520929263, 6119.143319935123, 6197.225346831646, 6085.269767627913, 6086.452219300284, 6095.878082438415, 6220.160278362544, 6348.365163483615], "value": 5284.969728207027, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.1926559732582, 380.76202373516554, 398.6392314954965, 399.04627939225276, 399.17171868373117, 4689.648276379273, 4861.031282330658, 5364.329458817648, 5537.667739935601, 5678.772849086032, 5360.383555891074, 5374.381946076202, 5388.865278368043, 5418.674879507233, 5552.4169962154, 4004.819783109767, 4394.516603173648, 4547.818087276513, 4565.246734288855, 4670.594643758467, 3918.119302734689, 4408.162449134654, 4422.955770442302, 4546.613537849286, 4572.862814115582, 3478.982605086971, 4490.0, 4547.045120524694, 4555.362249285103, 4556.453225612914, 4270.441288929518, 4295.468280963582, 4506.414166158394, 4517.683762136652, 4518.231900576893, 3236.110069730843, 3756.793376364299, 3983.24318379507, 4171.037514361338, 4176.937345939813, 4195.622393984556, 4602.884927876797, 4717.2452407614965, 4989.625778066623, 5193.714345710984, 4542.4776150742755, 4576.3821884045565, 4824.565789079, 5006.3241462402475, 5145.536901678867, 5181.305721025084, 5292.153255479144, 5370.3287089113865, 5839.795607153772, 5843.678597677169, 3909.885682580479, 4547.0, 4549.499555048942, 4568.817247310104, 4595.816167353302, 5128.974355128201, 5130.4356520782685, 5705.258316418881, 5753.798617048383, 5885.646861188342, 4378.905273681579, 4456.532064133284, 4654.255319148988, 4664.836730714409, 4674.415698037746, 4267.103908179255, 4733.455652599995, 4746.762661866893, 4748.857534273978, 4783.7129772213775, 4278.294081476522, 4947.406311242602, 5076.0, 5142.922856157161, 5203.401608815035, 5695.231143795535, 5701.289613116049, 5735.795482948628, 5735.942640573624, 5821.446962538636, 1408.457743768636, 1525.4677235788538, 1636.8281330460368, 1652.4629495413799, 1694.2629955969194, 1071.7159952612565, 1155.485808815086, 1176.7175877789268, 1183.7869183546873, 1244.4213440749913, 4659.720416775004, 5105.770240339194, 5386.0, 5390.407055223922, 5396.0843900415575, 5414.615865084323, 5587.944120558824, 6003.039513677878, 6006.969965150146, 6012.549058820606, 4687.492191677505, 4771.568529441223, 4943.237106714282, 5079.644424890296, 5381.48875856793, 2722.9591556126675, 2723.5506141486435, 3095.0, 3101.2712012677002, 3142.004826934867, 5249.973750131225, 5471.562275017989, 5908.977232048754, 5946.256717910262, 6128.559788449711, 4891.703698519895, 5582.701319983671, 5820.156077368758, 6042.0, 6104.969475152596, 5480.862978425492, 5659.320881494247, 6043.7884674036195, 6135.753930389453, 6202.247640177808, 5154.597965342856, 5658.377578466448, 5877.619564076555, 5939.049752039653, 6033.818985430444, 4927.531884470969, 5096.0, 5206.880520688071, 5689.402612725769, 5951.548102100684, 5190.169572868323, 6010.759569617341, 6055.788047418319, 6098.926365035909, 6197.132401463708, 4373.846915357947, 4622.930656040229, 4638.281066434708, 4643.955110100335, 4648.0, 5359.571234301325, 5584.748686309044, 5653.869226154703, 5889.734961926638, 5963.970180149245, 5200.97399513, 5526.696031718133, 5766.798162064308, 5786.971065144648, 5799.449052340021, 4979.402471703419, 5021.899562008666, 5380.735527151038, 5729.656220626609, 5829.7085145743395, 5491.313585801775, 5590.316648008898, 5612.242347283228, 5632.324121105494, 5664.714465609854, 5476.808311709071, 5546.667199968015, 5763.7982670606325, 5778.191053252632, 5847.532197424111, 4855.223164293696, 5264.815731449381, 5601.215829783752, 5664.49019588239, 5679.9148012779015], "value": 4370.646505521017, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.842533068056, 775.3487070860476, 799.4643588795507, 879.8108406692561, 884.5311984648139, 15324.0, 18082.457526274215, 18978.810211897893, 19153.61692766144, 19405.611887762272, 17961.820381796177, 18614.813851861476, 18633.068346582684, 18759.624807503842, 18876.056197190155, 704.0, 818.664347617477, 1002.9297949143545, 1025.3232866308238, 1026.2354545863304, 990.1039559198937, 1016.938983660979, 1018.4195008844962, 1028.408665017614, 1030.7114008077713, 835.6239692138562, 1069.4438891776272, 1077.3266708307308, 1077.9407132607703, 1080.2546243092281, 1052.952617132231, 1058.7035630023568, 1073.8926107389238, 1074.5379486820664, 1074.8871368506273, 789.3566743104352, 795.0737390939548, 795.4352409789057, 796.4066770256138, 796.7888509544975, 14362.425502979868, 17048.318067277294, 17251.30994760208, 17557.209925553252, 17711.0, 14666.413343466249, 14737.0, 14752.926235368861, 14780.926095369561, 14933.10401375921, 11898.46456909441, 11929.0, 12015.639530814089, 12111.0, 12139.15025948184, 3866.7679939203736, 3883.10688541637, 3898.7075969302414, 4035.535913369971, 4037.212743515016, 123942.14086788795, 127239.0, 127298.0, 128055.0, 129499.0, 10532.210084243712, 11446.942765286287, 11593.88406115928, 11664.358460284766, 11669.533218671328, 22822.543549128924, 23155.39525997311, 23641.881790590938, 23681.881590591936, 23774.64338034931, 8311.33509319253, 8336.374771892133, 8408.579571021424, 8521.701740439055, 8533.53065581399, 4607.44710634726, 4611.815527378934, 4675.859724208211, 4781.091592597395, 4899.265110233494, 3246.8701251949656, 3750.3999360102252, 3770.472133901256, 3788.299164654548, 3799.4870692456166, 586.9295684517886, 622.343427683789, 694.3160986428364, 702.2626242445411, 705.8870580707062, 671.1878626861552, 681.8295426143465, 695.9617221052789, 701.4863175441029, 736.8857827036818, 10625.570616789959, 10853.34879907208, 10908.672739817817, 11262.774744505228, 11385.08919286455, 682.4676752133344, 724.9093863267092, 741.9443541734391, 745.3552676934413, 763.6105586151103, 4439.334099884978, 4882.5605695486865, 5155.252488389237, 5265.447128051499, 5300.416954135042, 109900.0, 111715.0, 113192.43403783093, 114127.42936285265, 117286.0, 97293.5135324333, 99781.0, 100099.0, 102321.48839255905, 102360.48819755853, 84859.15140848512, 85479.14520854712, 85869.0, 87219.20294383743, 87517.68723469153, 9898.714316409276, 10061.496925153859, 10169.0, 10197.84703229467, 10206.897931020892, 44297.55702443063, 54941.88360799433, 56176.62046982838, 57456.71271643615, 57581.424185757605, 61504.384956149865, 66355.0, 70638.88083357508, 70734.0, 72131.63934180296, 11618.30290182558, 11639.5926142588, 11777.09775809847, 11818.46816893259, 12421.378931053592, 13061.21632702041, 13658.931705341409, 13697.657558561115, 13749.31253437304, 14316.642083947985, 12073.27560346382, 12485.438155283216, 12691.0, 12710.300933448749, 13142.80285795733, 11964.102692297938, 12300.0, 12435.37823108859, 12567.434465448894, 12747.043971701974, 11804.527818887493, 12250.877491224974, 12410.379481026093, 12683.80974285405, 12889.09776315649, 9053.211137052485, 9278.0, 9309.906900930904, 9454.810903782023, 9594.616215351307, 8142.679336389338, 9018.188363047357, 9427.905720942703, 9489.905100948901, 10538.420386878795], "value": 7938.437662781532, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 118.0, 120.0, 119.0, 120.0, 119.0, 119.0, 119.0, 123.0], "value": 118.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 110.0, 147.0, 110.0, 117.0, 110.0, 110.0, 128.0, 108.0], "value": 119.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 202.0, 206.0, 197.0, 197.0, 194.0, 195.0, 203.0, 199.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 68.0, 112.0, 87.0, 87.0, 64.0, 63.0, 72.0, 59.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 83.0, 71.0, 72.0, 70.0, 81.0, 71.0, 69.0, 82.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 92.0, 107.0, 93.0, 110.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 98.0, 99.0, 98.0, 99.0, 99.0, 98.0, 106.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 131.0, 89.0, 147.0, 128.0, 78.0, 115.0, 125.0, 74.0], "value": 106.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 70.0, 94.0, 94.0, 57.0, 86.0, 93.0, 107.0, 58.0, 92.0], "value": 80.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 88.0, 91.0, 61.0, 81.0, 59.0, 57.0, 63.0, 88.0, 85.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 85.0, 85.0, 86.0, 83.0, 93.0, 105.0, 84.0, 88.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 145.0, 167.0, 141.0, 133.0, 150.0, 146.0, 155.0, 178.0, 155.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 229.0, 166.0, 164.0, 182.0, 152.0, 228.0, 191.0, 231.0, 220.0], "value": 196.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 76.0, 62.0, 63.0, 66.0, 64.0, 65.0], "value": 66.0, "unit": "score"}]}]} 11:38:06 INFO - Return code: 0 11:38:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:38:06 ERROR - # TBPL SUCCESS # 11:38:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:38:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:38:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:38:06 INFO - 2017-05-24 11:38:06.924 screenresolution[1657:10601] starting screenresolution argv=screenresolution get 11:38:06 INFO - 2017-05-24 11:38:06.927 screenresolution[1657:10601] Display 0: 1600x1200x32@60 11:38:06 INFO - 2017-05-24 11:38:06.933 screenresolution[1658:10604] starting screenresolution argv=screenresolution list 11:38:06 INFO - Available Modes on Display 0 11:38:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:38:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:38:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:38:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:38:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:38:07 INFO - Intel Iris: 11:38:07 INFO - Chipset Model: Intel Iris 11:38:07 INFO - Type: GPU 11:38:07 INFO - Bus: Built-In 11:38:07 INFO - VRAM (Dynamic, Max): 1536 MB 11:38:07 INFO - Vendor: Intel (0x8086) 11:38:07 INFO - Device ID: 0x0a2e 11:38:07 INFO - Revision ID: 0x0009 11:38:07 INFO - Displays: 11:38:07 INFO - Display: 11:38:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:38:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:38:07 INFO - Display Serial Number: 1600x1200 60 11:38:07 INFO - Main Display: Yes 11:38:07 INFO - Mirror: Off 11:38:07 INFO - Online: Yes 11:38:07 INFO - Rotation: Supported 11:38:07 INFO - Return code: 0 11:38:07 INFO - Running post-action listener: _resource_record_post_action 11:38:07 INFO - [mozharness: 2017-05-24 18:38:07.171548Z] Finished run-tests step (success) 11:38:07 INFO - Running post-run listener: _resource_record_post_run 11:38:07 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 41306112; Write bytes: 424267264; Read time: 402; Write time: 1996 11:38:07 INFO - TinderboxPrint: CPU usage
54.6% 11:38:07 INFO - TinderboxPrint: I/O read bytes / time
41,306,112 / 402 11:38:07 INFO - TinderboxPrint: I/O write bytes / time
424,267,264 / 1,996 11:38:07 INFO - TinderboxPrint: CPU idle
2,163.8 (45.4%) 11:38:07 INFO - TinderboxPrint: CPU system
959.9 (20.1%) 11:38:07 INFO - TinderboxPrint: CPU user
1,645.9 (34.5%) 11:38:07 INFO - TinderboxPrint: Swap in / out
163,184,640 / 0 11:38:07 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 117703168; Write bytes: 113127424; Read time: 10804; Write time: 220 11:38:07 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 39299072; Write bytes: 298599936; Read time: 379; Write time: 1637 11:38:07 INFO - Running post-run listener: _upload_blobber_files 11:38:07 INFO - Blob upload gear active. 11:38:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:38:07 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 11:38:07 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'] 11:38:07 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 11:38:08 INFO - (blobuploader) - INFO - Open directory for files ... 11:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:08 INFO - (blobuploader) - INFO - Done attempting. 11:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:09 INFO - (blobuploader) - INFO - Done attempting. 11:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:38:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:10 INFO - (blobuploader) - INFO - Done attempting. 11:38:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:38:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:38:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:12 INFO - (blobuploader) - INFO - Done attempting. 11:38:12 INFO - (blobuploader) - INFO - Iteration through files over. 11:38:12 INFO - Return code: 0 11:38:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:38:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:38:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73b2704a5ce1c76b1bb12ef39d1f9716f6589261b0578b45d064d3efce60adc12232a0234214d0076b61b35cb5e3b2c0dd1428acc770499e14f06199ba50fcf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07ba83b2a039a1fcce0597dd6807a06999eb9f90e3948ef1b8d3000781dce5a4f805986c6858429dfffff71f4af1e6103f4547272b4601e4abe34c64d2ef7b73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba9c37dedabc9e43dc0d26de519aa1ae15b3c0066694bb72e761e7f93f68be61ab0f125fd8fb36bb97d6830c87aeb9d0708e3d36488d939839baccb9ffbb0b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce15513ee56c3b90fc586701c56139aa95931b0aeef30319939eba78df537900f7a993fc16eda288be31575f47bf38bffbb512a568c9474646261396bc38e58c"} 11:38:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:38:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:38:12 INFO - Contents: 11:38:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/73b2704a5ce1c76b1bb12ef39d1f9716f6589261b0578b45d064d3efce60adc12232a0234214d0076b61b35cb5e3b2c0dd1428acc770499e14f06199ba50fcf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07ba83b2a039a1fcce0597dd6807a06999eb9f90e3948ef1b8d3000781dce5a4f805986c6858429dfffff71f4af1e6103f4547272b4601e4abe34c64d2ef7b73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba9c37dedabc9e43dc0d26de519aa1ae15b3c0066694bb72e761e7f93f68be61ab0f125fd8fb36bb97d6830c87aeb9d0708e3d36488d939839baccb9ffbb0b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce15513ee56c3b90fc586701c56139aa95931b0aeef30319939eba78df537900f7a993fc16eda288be31575f47bf38bffbb512a568c9474646261396bc38e58c"} 11:38:12 INFO - Running post-run listener: copy_logs_to_upload_dir 11:38:12 INFO - Copying logs to upload dir... 11:38:12 INFO - mkdir: /builds/slave/test/build/upload/logs 11:38:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.055173 ========= master_lag: 1.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-24 11:38:14.257084) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-24 11:38:14.260953) ========= 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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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/73b2704a5ce1c76b1bb12ef39d1f9716f6589261b0578b45d064d3efce60adc12232a0234214d0076b61b35cb5e3b2c0dd1428acc770499e14f06199ba50fcf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07ba83b2a039a1fcce0597dd6807a06999eb9f90e3948ef1b8d3000781dce5a4f805986c6858429dfffff71f4af1e6103f4547272b4601e4abe34c64d2ef7b73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba9c37dedabc9e43dc0d26de519aa1ae15b3c0066694bb72e761e7f93f68be61ab0f125fd8fb36bb97d6830c87aeb9d0708e3d36488d939839baccb9ffbb0b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce15513ee56c3b90fc586701c56139aa95931b0aeef30319939eba78df537900f7a993fc16eda288be31575f47bf38bffbb512a568c9474646261396bc38e58c"} build_url:https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011390 build_url: 'https://queue.taskcluster.net/v1/task/Y0-sMlvgQEysXxRD8xTQ5A/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/73b2704a5ce1c76b1bb12ef39d1f9716f6589261b0578b45d064d3efce60adc12232a0234214d0076b61b35cb5e3b2c0dd1428acc770499e14f06199ba50fcf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/07ba83b2a039a1fcce0597dd6807a06999eb9f90e3948ef1b8d3000781dce5a4f805986c6858429dfffff71f4af1e6103f4547272b4601e4abe34c64d2ef7b73", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ba9c37dedabc9e43dc0d26de519aa1ae15b3c0066694bb72e761e7f93f68be61ab0f125fd8fb36bb97d6830c87aeb9d0708e3d36488d939839baccb9ffbb0b28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce15513ee56c3b90fc586701c56139aa95931b0aeef30319939eba78df537900f7a993fc16eda288be31575f47bf38bffbb512a568c9474646261396bc38e58c"}' ========= master_lag: 1.17 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-24 11:38:15.440644) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 11:38:15.441022) ========= 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.VoohEuhXQr/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.lkwqsUjZ8U/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005211 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 11:38:15.470814) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 11:38:15.471126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 11:38:15.471440) ========= ========= Total master_lag: 2.70 =========