builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0108 starttime: 1494872874.75 results: success (0) buildid: 20170515095425 builduid: d83dac17ae05489bb274c6bc7a8864db revision: 241a875b457fd4164cfe96f57865dd0969f20909 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.749301) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.749854) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.750171) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.776543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.776923) ========= 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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-15 11:27:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.9M=0s 2017-05-15 11:27:54 (28.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.188132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.989055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:54.989412) ========= 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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.036625 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:55.048071) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 11:27:55.048471) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 241a875b457fd4164cfe96f57865dd0969f20909 --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 241a875b457fd4164cfe96f57865dd0969f20909 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1D3tOeDXVk/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.IE6ewPVxw0/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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-15 11:27:55,154 truncating revision to first 12 chars 2017-05-15 11:27:55,154 Setting DEBUG logging. 2017-05-15 11:27:55,154 attempt 1/10 2017-05-15 11:27:55,155 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/241a875b457f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 11:27:56,432 unpacking tar archive at: mozilla-beta-241a875b457f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.555391 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 11:27:56.626570) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:56.626897) ========= script_repo_revision: 241a875b457fd4164cfe96f57865dd0969f20909 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:56.627324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:56.627725) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 11:27:56.652550) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 11:27:56.652943) ========= /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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:27:56 INFO - MultiFileLogger online at 20170515 11:27:56 in /builds/slave/test 11:27:56 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:27:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:27:56 INFO - {'append_to_log': False, 11:27:56 INFO - 'base_work_dir': '/builds/slave/test', 11:27:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:27:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:27:56 INFO - 'branch': 'Mozilla-Beta', 11:27:56 INFO - 'buildbot_json_path': 'buildprops.json', 11:27:56 INFO - 'config_files': ('talos/mac_config.py',), 11:27:56 INFO - 'default_actions': ('clobber', 11:27:56 INFO - 'read-buildbot-config', 11:27:56 INFO - 'download-and-extract', 11:27:56 INFO - 'populate-webroot', 11:27:56 INFO - 'create-virtualenv', 11:27:56 INFO - 'install', 11:27:56 INFO - 'run-tests'), 11:27:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:27:56 INFO - 'download_minidump_stackwalk': True, 11:27:56 INFO - 'download_symbols': 'ondemand', 11:27:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:27:56 INFO - 'tooltool.py': '/tools/tooltool.py', 11:27:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:27:56 INFO - '/tools/misc-python/virtualenv.py')}, 11:27:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:27:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:27:56 INFO - 'gecko_profile': False, 11:27:56 INFO - 'gecko_profile_interval': 0, 11:27:56 INFO - 'installer_path': 'installer.exe', 11:27:56 INFO - 'log_level': 'info', 11:27:56 INFO - 'log_name': 'talos', 11:27:56 INFO - 'log_to_console': True, 11:27:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:27:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:27:56 INFO - 'opt_config_files': (), 11:27:56 INFO - 'pip_index': False, 11:27:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:56 INFO - 'cmd': ('bash', 11:27:56 INFO - '-c', 11:27:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:56 INFO - 'enabled': True, 11:27:56 INFO - 'halt_on_failure': False, 11:27:56 INFO - 'name': 'check_screen_resolution'},), 11:27:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:27:56 INFO - 'cmd': ('bash', 11:27:56 INFO - '-c', 11:27:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:27:56 INFO - 'enabled': True, 11:27:56 INFO - 'halt_on_failure': False, 11:27:56 INFO - 'name': 'check_screen_resolution'},), 11:27:56 INFO - 'run_cmd_checks_enabled': True, 11:27:56 INFO - 'suite': 'dromaeojs-e10s', 11:27:56 INFO - 'system_bits': '32', 11:27:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:27:56 INFO - 'title': 't-yosemite-r7-0108', 11:27:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:27:56 INFO - 'use_talos_json': True, 11:27:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:27:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:27:56 INFO - 'work_dir': 'build'} 11:27:56 INFO - [mozharness: 2017-05-15 18:27:56.871976Z] Running clobber step. 11:27:56 INFO - Running pre-action listener: _resource_record_pre_action 11:27:56 INFO - Running main action method: clobber 11:27:56 INFO - rmtree: /builds/slave/test/build 11:27:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:27:59 INFO - Running post-action listener: _resource_record_post_action 11:27:59 INFO - [mozharness: 2017-05-15 18:27:59.366902Z] Finished clobber step (success) 11:27:59 INFO - [mozharness: 2017-05-15 18:27:59.367065Z] Running read-buildbot-config step. 11:27:59 INFO - Running pre-action listener: _resource_record_pre_action 11:27:59 INFO - Running main action method: read_buildbot_config 11:27:59 INFO - Using buildbot properties: 11:27:59 INFO - { 11:27:59 INFO - "project": "", 11:27:59 INFO - "product": "firefox", 11:27:59 INFO - "script_repo_revision": "production", 11:27:59 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:27:59 INFO - "repository": "", 11:27:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:27:59 INFO - "buildid": "20170515095425", 11:27:59 INFO - "pgo_build": "False", 11:27:59 INFO - "basedir": "/builds/slave/test", 11:27:59 INFO - "buildnumber": 46, 11:27:59 INFO - "slavename": "t-yosemite-r7-0108", 11:27:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:27:59 INFO - "platform": "yosemite_r7", 11:27:59 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:27:59 INFO - "branch": "mozilla-beta", 11:27:59 INFO - "repo_path": "releases/mozilla-beta", 11:27:59 INFO - "revision": "241a875b457fd4164cfe96f57865dd0969f20909", 11:27:59 INFO - "stage_platform": "macosx64", 11:27:59 INFO - "builduid": "d83dac17ae05489bb274c6bc7a8864db", 11:27:59 INFO - "slavebuilddir": "test" 11:27:59 INFO - } 11:27:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:27:59 INFO - Running post-action listener: _resource_record_post_action 11:27:59 INFO - [mozharness: 2017-05-15 18:27:59.370276Z] Finished read-buildbot-config step (success) 11:27:59 INFO - [mozharness: 2017-05-15 18:27:59.370389Z] Running download-and-extract step. 11:27:59 INFO - Running pre-action listener: _resource_record_pre_action 11:27:59 INFO - Running main action method: download_and_extract 11:27:59 INFO - mkdir: /builds/slave/test/build/tests 11:27:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:59 INFO - trying https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:27:59 INFO - Downloading https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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:28:00 INFO - Downloaded 1287 bytes. 11:28:00 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:28:00 INFO - Using the following test package requirements: 11:28:00 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:28:00 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:28:00 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:28:00 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:28:00 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:28:00 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:28:00 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:28:00 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:28:00 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:28:00 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:28:00 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:28:00 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:28:00 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:28:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:28:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:28:00 INFO - Fetch https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:28:02 INFO - Expected file size: 51227364 11:28:02 INFO - Obtained file size: 51227364 11:28:18 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:28:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:28:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:28:18 INFO - Fetch https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:28:20 INFO - Expected file size: 51227364 11:28:20 INFO - Obtained file size: 51227364 11:28:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:28:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:28:35 INFO - Fetch https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:28:36 INFO - Expected file size: 14404449 11:28:36 INFO - Obtained file size: 14404449 11:28:37 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:28:37 INFO - trying https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:28:37 INFO - Downloading https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:28:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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:28:39 INFO - Downloaded 59540102 bytes. 11:28:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:28:39 INFO - mkdir: /builds/slave/test/properties 11:28:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:28:39 INFO - Writing to file /builds/slave/test/properties/build_url 11:28:39 INFO - Contents: 11:28:39 INFO - build_url:https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:28:39 INFO - Running post-action listener: _resource_record_post_action 11:28:39 INFO - Running post-action listener: set_extra_try_arguments 11:28:39 INFO - [mozharness: 2017-05-15 18:28:39.938610Z] Finished download-and-extract step (success) 11:28:39 INFO - [mozharness: 2017-05-15 18:28:39.938764Z] Running populate-webroot step. 11:28:39 INFO - Running pre-action listener: _resource_record_pre_action 11:28:39 INFO - Running main action method: populate_webroot 11:28:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:28:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:28:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:28:39 INFO - '--tppagecycles', 11:28:39 INFO - '7'], 11:28:39 INFO - 'tests': ['tsvgm']}}, 11:28:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:28:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:28:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:28:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:28:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:28:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['damp', 'tps']}, 11:28:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'tests': ['damp', 'tps']}, 11:28:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['dromaeo_dom']}, 11:28:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:28:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['basic_compositor_video']}, 11:28:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:28:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['a11yr', 11:28:39 INFO - 'ts_paint', 11:28:39 INFO - 'tpaint', 11:28:39 INFO - 'sessionrestore', 11:28:39 INFO - 'sessionrestore_no_auto_restore', 11:28:39 INFO - 'tabpaint']}, 11:28:39 INFO - 'other-e10s': {'tests': ['a11yr', 11:28:39 INFO - 'ts_paint', 11:28:39 INFO - 'tpaint', 11:28:39 INFO - 'sessionrestore', 11:28:39 INFO - 'sessionrestore_no_auto_restore', 11:28:39 INFO - 'tabpaint']}, 11:28:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:28:39 INFO - 'ts_paint', 11:28:39 INFO - 'tpaint', 11:28:39 INFO - 'sessionrestore', 11:28:39 INFO - 'sessionrestore_no_auto_restore', 11:28:39 INFO - 'tabpaint']}, 11:28:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:28:39 INFO - 'ts_paint', 11:28:39 INFO - 'tpaint', 11:28:39 INFO - 'sessionrestore', 11:28:39 INFO - 'sessionrestore_no_auto_restore', 11:28:39 INFO - 'tabpaint']}, 11:28:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['a11yr', 11:28:39 INFO - 'ts_paint', 11:28:39 INFO - 'tpaint', 11:28:39 INFO - 'sessionrestore', 11:28:39 INFO - 'sessionrestore_no_auto_restore', 11:28:39 INFO - 'tabpaint']}, 11:28:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['a11yr', 11:28:39 INFO - 'ts_paint', 11:28:39 INFO - 'tpaint', 11:28:39 INFO - 'sessionrestore', 11:28:39 INFO - 'sessionrestore_no_auto_restore', 11:28:39 INFO - 'tabpaint']}, 11:28:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['tsvgx', 11:28:39 INFO - 'tsvgr_opacity', 11:28:39 INFO - 'tart', 11:28:39 INFO - 'tscrollx', 11:28:39 INFO - 'cart', 11:28:39 INFO - 'tsvg_static']}, 11:28:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:28:39 INFO - 'tsvgr_opacity', 11:28:39 INFO - 'tart', 11:28:39 INFO - 'tscrollx', 11:28:39 INFO - 'cart', 11:28:39 INFO - 'tsvg_static']}, 11:28:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'talos_options': ['--disable-e10s'], 11:28:39 INFO - 'tests': ['tp5o']}, 11:28:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:28:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'talos_options': ['--disable-e10s', 11:28:39 INFO - '--xperf_path', 11:28:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:39 INFO - 'tests': ['tp5n']}, 11:28:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:28:39 INFO - 'talos_options': ['--xperf_path', 11:28:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:28:39 INFO - 'tests': ['tp5n']}}, 11:28:39 INFO - 'talos.zip': {'path': '', 11:28:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:28:39 INFO - Running post-action listener: _resource_record_post_action 11:28:39 INFO - [mozharness: 2017-05-15 18:28:39.957327Z] Finished populate-webroot step (success) 11:28:39 INFO - [mozharness: 2017-05-15 18:28:39.957462Z] Running create-virtualenv step. 11:28:39 INFO - Running pre-action listener: _resource_record_pre_action 11:28:39 INFO - Running main action method: create_virtualenv 11:28:39 INFO - Creating virtualenv /builds/slave/test/build/venv 11:28:39 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:28:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:28:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:28:40 INFO - Using real prefix '/tools/python27' 11:28:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:28:40 INFO - Installing distribute.............................................................................................................................................................................................done. 11:28:43 INFO - Installing pip.................done. 11:28:43 INFO - Return code: 0 11:28:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:28:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:28:43 INFO - Reading from file tmpfile_stdout 11:28:43 INFO - Output received: 11:28:43 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:28:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:43 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28:43 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:28:43 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:28:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:43 INFO - 'HOME': '/Users/cltbld', 11:28:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:43 INFO - 'LOGNAME': 'cltbld', 11:28:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:43 INFO - 'MOZ_NO_REMOTE': '1', 11:28:43 INFO - 'NO_EM_RESTART': '1', 11:28:43 INFO - 'PAGER': '/bin/cat', 11:28:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:43 INFO - 'PWD': '/builds/slave/test', 11:28:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:43 INFO - 'SHELL': '/bin/bash', 11:28:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:43 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:43 INFO - 'USER': 'cltbld', 11:28:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:43 INFO - 'XPC_FLAGS': '0x0', 11:28:43 INFO - 'XPC_SERVICE_NAME': '0', 11:28:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:44 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:28:44 INFO - Cleaning up... 11:28:44 INFO - Return code: 0 11:28:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:28: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:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28: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:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:44 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28:44 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:28:44 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:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:44 INFO - 'HOME': '/Users/cltbld', 11:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:44 INFO - 'LOGNAME': 'cltbld', 11:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:44 INFO - 'MOZ_NO_REMOTE': '1', 11:28:44 INFO - 'NO_EM_RESTART': '1', 11:28:44 INFO - 'PAGER': '/bin/cat', 11:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:44 INFO - 'PWD': '/builds/slave/test', 11:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:44 INFO - 'SHELL': '/bin/bash', 11:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:44 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:44 INFO - 'USER': 'cltbld', 11:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:44 INFO - 'XPC_FLAGS': '0x0', 11:28:44 INFO - 'XPC_SERVICE_NAME': '0', 11:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:44 INFO - Downloading/unpacking psutil>=3.1.1 11:28:44 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:28:44 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:28:44 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:28:44 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:28:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:28:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:46 INFO - Installing collected packages: psutil 11:28:46 INFO - Running setup.py install for psutil 11:28:46 INFO - building 'psutil._psutil_osx' extension 11:28:46 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:28:47 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:28:47 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:28:47 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:28:47 INFO - building 'psutil._psutil_posix' extension 11:28:47 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:28:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:28:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:28:47 INFO - ^ 11:28:47 INFO - 1 warning generated. 11:28:47 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:28:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:28:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:28:47 INFO - Successfully installed psutil 11:28:47 INFO - Cleaning up... 11:28:47 INFO - Return code: 0 11:28:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:47 INFO - 'HOME': '/Users/cltbld', 11:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:47 INFO - 'LOGNAME': 'cltbld', 11:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:47 INFO - 'MOZ_NO_REMOTE': '1', 11:28:47 INFO - 'NO_EM_RESTART': '1', 11:28:47 INFO - 'PAGER': '/bin/cat', 11:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:47 INFO - 'PWD': '/builds/slave/test', 11:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:47 INFO - 'SHELL': '/bin/bash', 11:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:47 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:47 INFO - 'USER': 'cltbld', 11:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:47 INFO - 'XPC_FLAGS': '0x0', 11:28:47 INFO - 'XPC_SERVICE_NAME': '0', 11:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:28:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:28:49 INFO - Installing collected packages: mozsystemmonitor 11:28:49 INFO - Running setup.py install for mozsystemmonitor 11:28:49 INFO - Successfully installed mozsystemmonitor 11:28:49 INFO - Cleaning up... 11:28:49 INFO - Return code: 0 11:28:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:28:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:49 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28:49 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:28:49 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:28:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:49 INFO - 'HOME': '/Users/cltbld', 11:28:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:49 INFO - 'LOGNAME': 'cltbld', 11:28:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:49 INFO - 'MOZ_NO_REMOTE': '1', 11:28:49 INFO - 'NO_EM_RESTART': '1', 11:28:49 INFO - 'PAGER': '/bin/cat', 11:28:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:49 INFO - 'PWD': '/builds/slave/test', 11:28:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:49 INFO - 'SHELL': '/bin/bash', 11:28:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:49 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:49 INFO - 'USER': 'cltbld', 11:28:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:49 INFO - 'XPC_FLAGS': '0x0', 11:28:49 INFO - 'XPC_SERVICE_NAME': '0', 11:28:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:49 INFO - Downloading/unpacking jsonschema==2.5.1 11:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:28:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:51 INFO - Downloading functools32-3.2.3-2.tar.gz 11:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:28:51 INFO - warning: no files found matching '*.txt' 11:28:51 INFO - no previously-included directories found matching 'build' 11:28:51 INFO - no previously-included directories found matching 'dist' 11:28:51 INFO - no previously-included directories found matching '.git*' 11:28:51 INFO - Installing collected packages: jsonschema, functools32 11:28:51 INFO - Running setup.py install for functools32 11:28:51 INFO - warning: no files found matching '*.txt' 11:28:51 INFO - no previously-included directories found matching 'build' 11:28:51 INFO - no previously-included directories found matching 'dist' 11:28:51 INFO - no previously-included directories found matching '.git*' 11:28:51 INFO - Successfully installed jsonschema functools32 11:28:51 INFO - Cleaning up... 11:28:51 INFO - Return code: 0 11:28:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:28:51 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:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28: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:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28: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', '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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:28: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 functools32==3.2.3-2 11:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:52 INFO - 'HOME': '/Users/cltbld', 11:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:52 INFO - 'LOGNAME': 'cltbld', 11:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:52 INFO - 'MOZ_NO_REMOTE': '1', 11:28:52 INFO - 'NO_EM_RESTART': '1', 11:28:52 INFO - 'PAGER': '/bin/cat', 11:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:52 INFO - 'PWD': '/builds/slave/test', 11:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:52 INFO - 'SHELL': '/bin/bash', 11:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:52 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:52 INFO - 'USER': 'cltbld', 11:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:52 INFO - 'XPC_FLAGS': '0x0', 11:28:52 INFO - 'XPC_SERVICE_NAME': '0', 11:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:28:52 INFO - Cleaning up... 11:28:52 INFO - Return code: 0 11:28:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:28: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:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28: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:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:53 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28:53 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:28:53 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:28:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:53 INFO - 'HOME': '/Users/cltbld', 11:28:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:53 INFO - 'LOGNAME': 'cltbld', 11:28:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:53 INFO - 'MOZ_NO_REMOTE': '1', 11:28:53 INFO - 'NO_EM_RESTART': '1', 11:28:53 INFO - 'PAGER': '/bin/cat', 11:28:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:53 INFO - 'PWD': '/builds/slave/test', 11:28:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:53 INFO - 'SHELL': '/bin/bash', 11:28:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:53 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:53 INFO - 'USER': 'cltbld', 11:28:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:53 INFO - 'XPC_FLAGS': '0x0', 11:28:53 INFO - 'XPC_SERVICE_NAME': '0', 11:28:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:53 INFO - Downloading/unpacking blobuploader==1.2.4 11:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:28:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:28:55 INFO - Downloading blobuploader-1.2.4.tar.gz 11:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:28:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:28:55 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:28:55 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:28:55 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:28:55 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:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:28:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:28:55 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:28:55 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:28:55 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:28:55 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:28:55 INFO - Downloading docopt-0.6.1.tar.gz 11:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:28:56 INFO - Installing collected packages: blobuploader, requests, docopt 11:28:56 INFO - Running setup.py install for blobuploader 11:28:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:28:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:28:56 INFO - Running setup.py install for requests 11:28:56 INFO - Running setup.py install for docopt 11:28:56 INFO - Successfully installed blobuploader requests docopt 11:28:56 INFO - Cleaning up... 11:28:56 INFO - Return code: 0 11:28:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:28:56 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:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:28:56 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:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:28:56 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:28:56 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:28:56 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:28:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:28:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:28:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:28:56 INFO - 'HOME': '/Users/cltbld', 11:28:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:28:56 INFO - 'LOGNAME': 'cltbld', 11:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:28:56 INFO - 'MOZ_NO_REMOTE': '1', 11:28:56 INFO - 'NO_EM_RESTART': '1', 11:28:56 INFO - 'PAGER': '/bin/cat', 11:28:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:28:56 INFO - 'PWD': '/builds/slave/test', 11:28:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:28:56 INFO - 'SHELL': '/bin/bash', 11:28:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:28:56 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:28:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:28:56 INFO - 'USER': 'cltbld', 11:28:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:28:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:28:56 INFO - 'XPC_FLAGS': '0x0', 11:28:56 INFO - 'XPC_SERVICE_NAME': '0', 11:28:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-oQxEzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-388v1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-oU_DxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ggNx5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-3rjoPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-pKWM7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-S7geyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:28:57 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Xb2A_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-IHBpW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-uRE4Vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4Z6DPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ZCXEt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-HOmNmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-WoQJv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-pTOcdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-UO8udg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:28:58 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-X9Sbeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:28:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:28:58 INFO - Running setup.py install for manifestparser 11:28:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:28:59 INFO - Running setup.py install for mozcrash 11:28:59 INFO - Running setup.py install for mozdebug 11:28:59 INFO - Running setup.py install for mozdevice 11:28:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:28:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:28:59 INFO - Running setup.py install for mozfile 11:28:59 INFO - Running setup.py install for mozhttpd 11:28:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:28:59 INFO - Running setup.py install for mozinfo 11:29:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Running setup.py install for mozInstall 11:29:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Running setup.py install for mozleak 11:29:00 INFO - Running setup.py install for mozlog 11:29:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Running setup.py install for moznetwork 11:29:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Running setup.py install for mozprocess 11:29:00 INFO - Running setup.py install for mozprofile 11:29:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:29:00 INFO - Running setup.py install for mozrunner 11:29:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:29:01 INFO - Running setup.py install for mozscreenshot 11:29:01 INFO - Running setup.py install for moztest 11:29:01 INFO - Running setup.py install for mozversion 11:29:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:29:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:29:01 INFO - Cleaning up... 11:29:01 INFO - Return code: 0 11:29:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29: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:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29: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:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:01 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:29:01 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:29:01 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:29:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:29:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:01 INFO - 'HOME': '/Users/cltbld', 11:29:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:01 INFO - 'LOGNAME': 'cltbld', 11:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:01 INFO - 'MOZ_NO_REMOTE': '1', 11:29:01 INFO - 'NO_EM_RESTART': '1', 11:29:01 INFO - 'PAGER': '/bin/cat', 11:29:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:01 INFO - 'PWD': '/builds/slave/test', 11:29:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:01 INFO - 'SHELL': '/bin/bash', 11:29:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:29:01 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:29:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:01 INFO - 'USER': 'cltbld', 11:29:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:01 INFO - 'XPC_FLAGS': '0x0', 11:29:01 INFO - 'XPC_SERVICE_NAME': '0', 11:29:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:29:01 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-v0oJIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:29:01 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:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:29:01 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-pTqND6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-HCFjnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-kUkxeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-c_NVB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-OaoWPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-hnvZb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-g1LTDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ftu3N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-zqnprt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:29:02 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:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:29:02 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-QFe1Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:29:03 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:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:29:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-lg56qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:29:03 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:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:29:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-HqPQ9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:29:03 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:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:29:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-0zLiZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:29:03 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:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-hzLYLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:29:03 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:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:29:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2WOsDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:29:03 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:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:29:03 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4ZwWVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:29:03 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:29:03 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:29:03 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:29:03 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:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:29:03 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:29:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:29: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:29: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:29: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:29: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:29:05 INFO - Downloading blessings-1.6.tar.gz 11:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:29:05 INFO - Installing collected packages: blessings 11:29:05 INFO - Running setup.py install for blessings 11:29:05 INFO - Successfully installed blessings 11:29:05 INFO - Cleaning up... 11:29:05 INFO - Return code: 0 11:29:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:29:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:05 INFO - Reading from file tmpfile_stdout 11:29:05 INFO - Current package versions: 11:29:05 INFO - blessings == 1.6 11:29:05 INFO - blobuploader == 1.2.4 11:29:05 INFO - docopt == 0.6.1 11:29:05 INFO - functools32 == 3.2.3-2 11:29:05 INFO - jsonschema == 2.5.1 11:29:05 INFO - manifestparser == 1.1 11:29:05 INFO - mozInstall == 1.12 11:29:05 INFO - mozcrash == 1.0 11:29:05 INFO - mozdebug == 0.1 11:29:05 INFO - mozdevice == 0.49 11:29:05 INFO - mozfile == 1.2 11:29:05 INFO - mozhttpd == 0.7 11:29:05 INFO - mozinfo == 0.9 11:29:05 INFO - mozleak == 0.1 11:29:05 INFO - mozlog == 3.4 11:29:05 INFO - moznetwork == 0.27 11:29:05 INFO - mozprocess == 0.25 11:29:05 INFO - mozprofile == 0.28 11:29:05 INFO - mozrunner == 6.13 11:29:05 INFO - mozscreenshot == 0.1 11:29:05 INFO - mozsystemmonitor == 0.3 11:29:05 INFO - moztest == 0.8 11:29:05 INFO - mozversion == 1.4 11:29:05 INFO - psutil == 3.1.1 11:29:05 INFO - requests == 1.2.3 11:29:05 INFO - wsgiref == 0.1.2 11:29:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:29:05 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:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:05 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:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:05 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:29:05 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:29:05 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:29:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:29:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:05 INFO - 'HOME': '/Users/cltbld', 11:29:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:05 INFO - 'LOGNAME': 'cltbld', 11:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:05 INFO - 'MOZ_NO_REMOTE': '1', 11:29:05 INFO - 'NO_EM_RESTART': '1', 11:29:05 INFO - 'PAGER': '/bin/cat', 11:29:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:05 INFO - 'PWD': '/builds/slave/test', 11:29:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:05 INFO - 'SHELL': '/bin/bash', 11:29:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:29:05 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:29:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:05 INFO - 'USER': 'cltbld', 11:29:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:05 INFO - 'XPC_FLAGS': '0x0', 11:29:05 INFO - 'XPC_SERVICE_NAME': '0', 11:29:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 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:29:05 INFO - Cleaning up... 11:29:05 INFO - Return code: 0 11:29:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:29:05 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:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:29:05 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:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:29:05 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 0x10af42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf93d73f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, '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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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:29:05 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:29:05 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:29:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:29:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:05 INFO - 'HOME': '/Users/cltbld', 11:29:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:05 INFO - 'LOGNAME': 'cltbld', 11:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:05 INFO - 'MOZ_NO_REMOTE': '1', 11:29:05 INFO - 'NO_EM_RESTART': '1', 11:29:05 INFO - 'PAGER': '/bin/cat', 11:29:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:05 INFO - 'PWD': '/builds/slave/test', 11:29:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:05 INFO - 'SHELL': '/bin/bash', 11:29:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:29:05 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:29:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:05 INFO - 'USER': 'cltbld', 11:29:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:05 INFO - 'XPC_FLAGS': '0x0', 11:29:05 INFO - 'XPC_SERVICE_NAME': '0', 11:29:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:29:06 INFO - Cleaning up... 11:29:06 INFO - Return code: 0 11:29:06 INFO - Running post-action listener: _resource_record_post_action 11:29:06 INFO - Running post-action listener: _start_resource_monitoring 11:29:06 INFO - Starting resource monitoring. 11:29:06 INFO - [mozharness: 2017-05-15 18:29:06.259526Z] Finished create-virtualenv step (success) 11:29:06 INFO - [mozharness: 2017-05-15 18:29:06.260416Z] Running install step. 11:29:06 INFO - Running pre-action listener: _resource_record_pre_action 11:29:06 INFO - Running main action method: install 11:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:29:06 INFO - Reading from file tmpfile_stdout 11:29:06 INFO - Detecting whether we're running mozinstall >=1.0... 11:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:29:06 INFO - Reading from file tmpfile_stdout 11:29:06 INFO - Output received: 11:29:06 INFO - Usage: mozinstall [options] installer 11:29:06 INFO - Options: 11:29:06 INFO - -h, --help show this help message and exit 11:29:06 INFO - -d DEST, --destination=DEST 11:29:06 INFO - Directory to install application into. [default: 11:29:06 INFO - "/builds/slave/test"] 11:29:06 INFO - --app=APP Application being installed. [default: firefox] 11:29:06 INFO - mkdir: /builds/slave/test/build/application 11:29:06 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:29:06 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:29:21 INFO - Reading from file tmpfile_stdout 11:29:21 INFO - Output received: 11:29:21 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:29:21 INFO - Running post-action listener: _resource_record_post_action 11:29:21 INFO - [mozharness: 2017-05-15 18:29:21.690833Z] Finished install step (success) 11:29:21 INFO - [mozharness: 2017-05-15 18:29:21.690988Z] Running run-tests step. 11:29:21 INFO - Running pre-action listener: _resource_record_pre_action 11:29:21 INFO - Running main action method: run_tests 11:29:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:21 INFO - 2017-05-15 11:29:21.705 screenresolution[1625:7361] starting screenresolution argv=screenresolution get 11:29:21 INFO - 2017-05-15 11:29:21.708 screenresolution[1625:7361] Display 0: 1600x1200x32@60 11:29:21 INFO - 2017-05-15 11:29:21.713 screenresolution[1626:7363] starting screenresolution argv=screenresolution list 11:29:21 INFO - Available Modes on Display 0 11:29:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:21 INFO - Intel Iris: 11:29:21 INFO - Chipset Model: Intel Iris 11:29:21 INFO - Type: GPU 11:29:21 INFO - Bus: Built-In 11:29:21 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:21 INFO - Vendor: Intel (0x8086) 11:29:21 INFO - Device ID: 0x0a2e 11:29:21 INFO - Revision ID: 0x0009 11:29:21 INFO - Displays: 11:29:21 INFO - Display: 11:29:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:21 INFO - Display Serial Number: 1600x1200 60 11:29:21 INFO - Main Display: Yes 11:29:21 INFO - Mirror: Off 11:29:21 INFO - Online: Yes 11:29:21 INFO - Rotation: Supported 11:29:21 INFO - Return code: 0 11:29:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:29:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:29:21 INFO - Python 2.7.3 11:29:21 INFO - Return code: 0 11:29:21 INFO - grabbing minidump binary from tooltool 11:29:21 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:29:21 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 0x10ad20d30>, '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 0x10ac47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad15be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:29:21 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:29:21 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:29:21 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:29:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:29:22 INFO - Return code: 0 11:29:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:29:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:29:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:29:22 INFO - ENV: RUST_BACKTRACE is now 1 11:29:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:29:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:29:22 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/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--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:29:22 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/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --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:29:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1D3tOeDXVk/Render', 11:29:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:29:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:29:22 INFO - 'HOME': '/Users/cltbld', 11:29:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:29:22 INFO - 'LOGNAME': 'cltbld', 11:29:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:29:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:29:22 INFO - 'MOZ_NO_REMOTE': '1', 11:29:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:29:22 INFO - 'NO_EM_RESTART': '1', 11:29:22 INFO - 'PAGER': '/bin/cat', 11:29:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:29:22 INFO - 'PWD': '/builds/slave/test', 11:29:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:29:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:29:22 INFO - 'RUST_BACKTRACE': '1', 11:29:22 INFO - 'SHELL': '/bin/bash', 11:29:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE6ewPVxw0/Listeners', 11:29:22 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 11:29:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:29:22 INFO - 'USER': 'cltbld', 11:29:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:29:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:29:22 INFO - 'XPC_FLAGS': '0x0', 11:29:22 INFO - 'XPC_SERVICE_NAME': '0', 11:29:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:29:22 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/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--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:29:22 INFO - mozversion application_buildid: 20170515095425 11:29:22 INFO - mozversion application_changeset: 241a875b457fd4164cfe96f57865dd0969f20909 11:29:22 INFO - mozversion application_display_name: Firefox 11:29:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:29:22 INFO - mozversion application_name: Firefox 11:29:22 INFO - mozversion application_remotingname: firefox 11:29:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:29:22 INFO - mozversion application_vendor: Mozilla 11:29:22 INFO - mozversion application_version: 54.0 11:29:22 INFO - mozversion platform_buildid: 20170515095425 11:29:22 INFO - mozversion platform_changeset: 241a875b457fd4164cfe96f57865dd0969f20909 11:29:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:29:22 INFO - mozversion platform_version: 54.0 11:29:22 INFO - using testdate: 1494872962 11:29:22 INFO - actual date: 1494872962 11:29:22 INFO - starting webserver on 'localhost:49226' 11:29:22 INFO - SUITE-START | Running 2 tests 11:29:22 INFO - TEST-START | dromaeo_css 11:29:22 INFO - operating with platform_type : mac_ 11:29:22 INFO - Initialising browser for dromaeo_css test... 11:29:22 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpGf_2lL/profile http://localhost:49226/getInfo.html) 11:29:25 INFO - PROCESS | 1636 | 2017-05-15 11:29:25.267 plugin-container[1640:7537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 11:29:25 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:29:25 INFO - PROCESS | 1636 | 2017-05-15 11:29:25.268 plugin-container[1640:7537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 11:29:25 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:29:25 INFO - PROCESS | 1636 | 2017-05-15 11:29:25.268 plugin-container[1640:7537] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:29:25 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 11:29:25 INFO - PROCESS | 1636 | colorDepth:24 11:29:25 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 11:29:25 INFO - PROCESS | 1636 | __metrics 11:29:25 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:29:26 INFO - TEST-INFO | 1636: exit 0 11:29:26 INFO - Browser initialized. 11:29:26 INFO - Running cycle 1/1 for dromaeo_css test... 11:29:26 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpGf_2lL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:28 INFO - PROCESS | 1641 | 2017-05-15 11:29:28.201 plugin-container[1642:7675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 11:29:28 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:29:28 INFO - PROCESS | 1641 | 2017-05-15 11:29:28.201 plugin-container[1642:7675] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870f, name = 'com.apple.CFPasteboardClient' 11:29:28 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:29:28 INFO - PROCESS | 1641 | 2017-05-15 11:29:28.201 plugin-container[1642:7675] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:29:38 INFO - PROCESS | 1641 | RSS: Main: 174219264 11:29:38 INFO - PROCESS | 1641 | 11:32:41 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 11:32:42 INFO - PROCESS | 1641 | RSS: Main: 189673472 11:32:42 INFO - PROCESS | 1641 | 11:35:44 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 11:35:45 INFO - PROCESS | 1641 | RSS: Main: 202960896 11:35:45 INFO - PROCESS | 1641 | 11:38:47 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 11:38:48 INFO - PROCESS | 1641 | RSS: Main: 204562432 11:38:48 INFO - PROCESS | 1641 | 11:41:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 11:41:50 INFO - PROCESS | 1641 | RSS: Main: 208777216 11:41:50 INFO - PROCESS | 1641 | 11:44:53 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 11:44:54 INFO - PROCESS | 1641 | RSS: Main: 211152896 11:44:54 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 11:47:57 INFO - PROCESS | 1641 | RSS: Main: 209178624 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | __start_tp_report 11:47:57 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 11:47:57 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 11:47:57 INFO - PROCESS | 1641 | |i|pagename|runs| 11:47:57 INFO - PROCESS | 1641 | |0;dojo.html;3029.757619390449;3609.3142302962433;3611.6568925952024;3634.2368102698433;3642.1258897864504;6210.689465526728;6220.906686399708;6222.377762223776;6226.657533835643;6237.313895471494;6173.567850250484;6214.65819379934;6231.875362492748;6242.625442473455;6246.063090536421;5607.130894711327;5645.491905728483;5656.858578535528;5664.20701101846;5755.45323194298;5722.942770572283;5747.137929310595;5749.080147176453;5770;5776.826695199151;5324.414314425429;5499.037668408033;5538.806141785039;5703.287089113861;5742.655440673552;5621.606487545873;5624.3250809903075;5650.237217975603;5666.206731057655;5681.261436013333;5625;5691.03252447085;5692.430756924316;5693.089105743061;5733.827985160452;6268.122462855204;6295.527835412362;6303.338149494283;6306.747730090791;6307.337729538378;5674.8933957878235;6279.591826531261;6322;6430.1319321891415;6717.899231511531;6592.76925307612;6624.701888415033;6674.666266686695;6676.198856137295;6686.3982241598005;6256.812295631138;6286.654234017127;6293.465055470337;6308.653024083674;6318.2733985591785;6638.5684930479365;6881.759138430131;6951.582905025714;6971.721131154697;7006.649667516604;6322.304546499881;6324.43080122782;6341.587796793194;6356.396142366468;6367.872642547123;6664.433523150489;6680.19837619479;6694.397504224643;6699.095622091053;6707.295733947865;6272.027835685385;6445.484361251089;6448.065030570541;6481.643509606923;6482.57863238888;6404.423601875854;6516.152900122954;6534.248561415403;6575.868482630321;6603.7688680895935;4392.538783427694;5187.299714538489;5237.738113094406;5320.733963301819;5410.702411367334;4795.808167673324;4809.206480930678;4810.927836082462;4818.084563932842;4830.541098595628;6228.56400051992;6237.220347456568;6275.874482510324;6283.120363149163;6312.210973628296;6406.551541392151;6457.61254324742;6596.505262105361;6645.169353830771;6795.558288711317;6289.308176100625;6293.244810622755;6299.055141728686;6300.59046161998;6326.430621244018;5957.017092179742;5979.790707325223;5985.71692359896;6005.849853753691;6006.579539432285;6568.86862262752;6652.334766523309;6825.761098361533;6829.146356705412;6949;6790.7623233186605;6810.523263371517;6811.352921472452;6811.625360605016;6819.624920629413;6746.291639377991;6893.620850853251;6920.9307906920285;6954.756583519755;7015.123109611299;6043.516518678407;6334.904976425265;6529;6701.899471507833;6710.932890671226;6639.070530125689;6702.932970670231;6782.287859774653;6784.6946887389195;6805.523613347216;6206.510437495059;6555.016747487724;6560.9343906562235;6562.343765623399;6564.277929427663;6172.19761431029;6231.594946328474;6285.811425657238;6559.934400655932;6562.409383155539;6148;6281.183446151971;6304.117423560613;6316.242050953915;6365.522585806176;5562;6532;6546.0508226305965;6553.606783593;6557.311482294481;6186.969065154645;6209.65846878408;6282.497400207881;6295.464885484602;6340.841478963062 11:47:57 INFO - PROCESS | 1641 | |1;ext.html;773.5783997721242;822.6298165825378;843.2073850580456;977.0669011094406;978.0366339155935;38406.807965960164;39194.41208381873;40951;40972.770816875505;41242.58757412425;39369.40945885814;39696.404553931716;39780.80109599455;39843.20313593726;39880.40179397304;8317.500949943007;8434.789130271762;11035.172362072837;11328.320300781937;11549.826752598718;11241;11466.369349685763;11471.426428678575;11472.4837382318;11502.597409090684;11450.854914508565;11475.655730328104;11479.770404591904;11488.597899073535;11528.365939873303;11396.886031139748;11427;11429.428528573539;11431.828522572127;11432.42837858112;11028.944855275753;11038.944805276002;11105.777884442266;11127.165462590337;11183.664490065312;35523.82238088793;35631.64368356309;35700.107497312514;35731.4640280396;35751.82124089389;3928.2143571285856;3973.940390894139;3993.2612466693463;4006.4390985262085;4077.2864748669163;26159;26270;26419.867900660567;27886.163415097377;28170.436591268266;3238.5951756030495;3283.753718471124;3312.801231926068;3318.767686261963;3435.484677298425;337715;343806;371884;374671;386212;44402.77798610986;44474.55525444704;44479.77760111179;44699.882502937035;44744.10511789746;45402.864928376395;45651.315230271575;45803.16787328469;45829.54170458319;45847.541524584325;14270.001099922905;14395.42418303277;14438.494652687315;14444.783328250085;14460.493882714054;10409.271351005356;12670.49318027287;12716.237025778299;12717.872821271669;12740.044496662787;10037.899621003697;12009.879901200877;12108.81836772449;12156;12191.329476878856;5037.370328708911;5608.130739735266;5629.521490673339;5629.746661400247;5645.774169033275;942.8585712143097;976.3556053005125;978.3885071829964;984.3995162950595;1001.7245257554231;25923.870380648354;26994.190174294803;28033.859830701123;28469.857650711612;28564.857175713987;1062.4369084385135;1080.9621663241749;1086.2613422872523;1109.6227282723912;1118.0664145438486;12316.81524777129;14654.926725366306;14655.780163297559;14953.252337383088;14992.400303987719;419346;423189;424230;424967;425001;56410.717946410005;56626;56741.71629141828;56933.14600280999;57126.143107852586;1370.6230786533783;1490.2846633615925;1493.2757612558119;1525.4432132271863;1535.08662345904;26410.603840942018;27602.171934841987;27632.032878849142;27939;28008.71991280061;45454.77272613616;45497;45553.08893822039;45665.31502027406;45805.31292030556;330774;331960;332059;332316;332440;30601.693983060773;31347.529787053667;31433.21416964594;31466.842665786524;32268.838655806572;39037.609623903394;40169.39745903872;41000.79499602602;41306.586934130275;41372;21940;27268.45463090767;28389.858050709616;28462.430751384443;29185.85407072951;28147.859260703564;28211.858940705984;28291.292717682223;29024.56463153012;29098.27254318659;28832.855835720686;29321.41357172802;33019.504707429885;33172.834135829165;33749.66250337465;27320;28880.85559572189;29055.56416653794;29442.411151777273;29688.554671680373;21953.341399758032;23073.769262307163;23229.76770232276;23371.182008630298;23463.41341466477 11:47:57 INFO - PROCESS | 1641 | |2;jquery.html;391.39724823771394;418.93925380819803;420.4156222850237;428.8370419240689;462.58136386570146;19860;20988.370348889537;22204.222852200135;22312;22352.88823555882;22196.8901554922;22809.657855132187;23334.533309333845;23657.290281291545;24017.438866473665;6843.589384636925;7027.262137475568;7035.5075144739885;7105.040819489354;7110.360067593915;5748.367679555246;6947.756828511003;6981.860362792741;6987.441004719635;7014.859702805941;6655.168103987002;6959.234484206733;6963.721451141948;6992.19589747178;7051.89422158668;6604.603723776589;6899.482538809584;6939.444844412435;6962.547434416772;6964.930350696504;6139.508839292846;6675.232348279961;6742.190937087532;6811.114555107853;6842.521023528355;27599.724002759915;27672.723272767416;27934.44131117366;28077.157685269474;28335.43329133406;2926.8682909269132;2945.7054294570585;2954.3205062835455;2957.1719918422878;2963.0370129707717;16693.83306166935;17472.388466403743;17664;17780.288788448448;17894.731579026324;1841.6777064013745;2081.948615948936;2128.4040468668795;2151.107290474451;2152.042341158185;124850;139152;145202;152740;157456;17750.822491774918;20957.22769901402;21442.892785536285;21528.677069843965;21834.126634934742;32638;32961.175970600925;33364;33476.49785253223;33496.83251583727;8161.918380816234;9911.603535858485;11096.722581935515;11233;11250.437478126061;7668.693252269847;9232.446053236694;9239.815203695889;9240.168384845396;9371.906280937103;8797.780055498535;9162.175404213653;9827.901720982698;9966.900330996743;9994.350367226107;3629.564452265745;4199.517055538591;4283.265277562592;4311.48262208537;4314.870553883388;940.8870935487648;1078.3260462211117;1087.3856271206744;1112.933224006562;1121.3216004317271;18033.7294940576;21817.454563636034;22583.87080645961;22606.773932260796;22945;979.314479864098;1012.2610494339026;1102.597551893569;1138.3795831271811;1138.7950168969498;10849.186311026546;11501.482433290523;11515.942420287845;11566.653000409833;11575.76848463026;780653;780722;781176;792647;808092;144879.2756036213;151858;153646;156661;160157;1265.0828149591473;1290.7934730443085;1325.2313658078099;1326.7412854493282;1344.6907211341447;16364.75452868184;16694.749578756084;18087.638247234718;19039.237913410583;19983.500412489804;50260;56841.43158568361;63951;64165;65602.67198663976;90763.0923690781;90853.54573227356;92096.53951730198;92552;92623;11551.884481155417;11792.52829886795;11800.586979455678;11839.644810655349;12132.393380331125;12970.80543791825;12997.675058123623;13037.282949437871;13270.867291326964;13402.93298533501;11425.828612570984;11452.312861228353;11589.82615260755;11632.360220187971;11652.708682283012;10457.60121467604;10544.314619549705;10613;10657.840132397865;10781.676549703521;9426.198773104088;10871;11617.941910290732;11799.52801887915;11993.520259189534;11603.419829008684;11698.590549330733;11784.469698863742;12029.699257518632;12045.33750643688;9031.322650801347;10095.545700443645;10741.677749667522;11467;11471.426428678365 11:47:57 INFO - PROCESS | 1641 | |3;mootools.html;1614.2735768903997;1628.8126865410482;1649.8845080844346;1654.106782337538;1661.0615002523573;11103.278286911345;11446.48490817913;11692.240004399708;11734.530618775249;11866.22869513481;11663.941680291608;11813.114016448757;11832.822507662391;11839.940800296008;11854.585089521868;2510;2547.898084076635;2550.2859199424192;2551.808614353931;2552.1577879299784;2482.615194644833;2494.9396506484763;2514.358838496181;2517.34549017255;2558.3859873630286;2481.3424442522746;2494.5260400523935;2520.143151328548;2525.103588226181;2527.5703130467864;2395.5448464791634;2407.121400688736;2407.9759202408013;2485.7389974052735;2500.6874140732407;2480;2508.4857604191097;2511.3344963584664;2512.497500499889;2514.660520829701;11519.193656444053;11703.590374336858;11722.472488738027;11737.53049878004;11763.35301558414;6073.908891366633;6251.593646412968;6257.092721555349;6271.843203919893;6288.62268263901;3595.26297109092;3698.9260214795554;3725.590185079639;3745.7190710696536;3812.370958791796;1323.7948118041622;1944.922203111888;1953.765548134233;1991.6813309870352;2029.482481967089;14355.425782968668;14659.560213193621;15609;16342.673146537003;16518.917405413016;4853.854384368475;4887.29134275528;5006.849794506171;5056.06462804375;5059.114654935372;12157;12163.02695784335;12167.634970950885;12176.939115304544;12179.75640487203;6119.632822030692;6128.111423843607;6141.508679305645;6156.5998210116195;6160.507159427235;5474.987127381447;5634.098544232903;5689.544836413077;5690.430956904359;5778.219940308089;3987.441758153861;4100.097978444737;4101.651359634405;4138.296489596776;4181.51912530063;971.9805603887883;1101.498818037791;1109.2457129152253;1109.4341885638382;1121.4224674292745;942.5569982108398;952.9380590261611;964.1226483899618;965.8985806490217;1006.1642473652785;6652.70062847173;6661.700223489857;6710.463162946952;6896.724131034803;6899.724011039602;955.1212884146602;995.576879826071;1019.4393083803878;1035.9689209323733;1060.1518784972145;4197.685173611931;4412.205802955435;4460.018795864905;4519.751413672214;4540.455145382575;13745.175289482662;14442.927785361006;14485.420583176765;14496.637584060272;14510.709785804225;14033.298335083206;14288.642783930485;14438.350274237684;14491.420343186366;14522.564323070113;8999.82000359989;9134;10664.413457259925;10798.67603971882;10809.189310801721;6297.370262973666;7170.103737032871;7190.928090719235;7303;7310.342069213796;12043.879561204276;12142.817857732318;12146.331951742739;12410.75178496443;12445.564405245774;12413.813792792935;12451.879330860265;12656.6835829105;13089.738205235652;13240.53658121961;8624.09447008055;8714.738557843275;9211.631534738535;9500.42997420157;9595.952020239854;10909.127269818237;10961.671149865198;10986.285891417032;10990.780184396428;11077.667669969915;9122.726318210463;9126.817463650823;9164.954175229348;9183.173514383738;9224.538773061186;8121.431499794958;8884.422512536665;8980.416272942499;9018.143276388733;9040.773980650536;8073.717419890276;8128.187181281824;8150.7147249845975;8255.504669719836;8270.214329638675;5348.251244825649;5558.610897237155;6052.273727152769;6106.755729770759;6279.215098112736;5224.843254702365;6053.818385448443;6084.817455476343;6135.815925522242;6141.447269745744 11:47:57 INFO - PROCESS | 1641 | |4;prototype.html;376.9340365436048;383.327260657546;394.77300552182504;394.99407508887356;401.90957034667196;5451.373092094401;5504.477074677904;5617.269754931858;5644.915326270109;5648.378678345389;4719.976400117995;5318.3939869463875;5374.6237763356585;5436.510714035736;5477.75350109245;3502.7197824174004;3658.1951970566433;3672.926541469182;3962.988129021935;4122.876313710593;4304.816175551717;4420.911581768362;4481.731096134243;4503.378783637888;4565.680402371835;4118.6499147572495;4156.877643036379;4473.463184417875;4482.61897738691;4522.683412161134;3707.258548290353;3936.881893543198;4155.542890282067;4364.49808272048;4474.910501789962;3745.419459983707;3922.509446410346;4084.530279017921;4098.4876890388705;4176.809609261346;4399.450068741407;5269.552088072518;5305.4164041955355;5317.6174194709265;5356.90183512379;4432.667549933735;4656.231721765905;4983.8006479741125;5143.7685304161405;5227.457899919533;5271.604629652791;5723.742431590588;5771.797987070474;5802.955468015755;5828.854278643068;3868.6905047596124;4306.3755755415605;4585.449746030497;4614.653900957442;4648.976755116237;5190.429052804227;5348.331458567679;5851.38560451155;5996.430339117734;6170.907436388457;3800.771953682788;4364.410804541346;4704.458987216514;4711.74085425298;4735.83424580138;4277.187334406515;4479.440069991251;4805.399325084364;4816.903661926742;4823.335949097626;4831.661783675179;4890.559849613481;4908.631852611068;5117.334746482933;5206;5254.395744489356;5751.281089863767;5764.645308352534;5782.277215348082;5792.884142317214;1336.4453751693134;1486.9925650371895;1551.8680912122595;1635.623806524506;1648.8705236912754;1055.889131641182;1083.009046722256;1145.6505765741529;1212.0727643352843;1239.6983167673907;4389.934150987738;5243.217306115863;5436.646617969819;5439.428859969726;5468.316460442445;5177;5239.449857764956;5385.649932754359;6016.759329626853;6018.007028840192;5006.299118123466;5367.463253674601;5382.3541175059245;5456.236126942231;5461.9453805462235;2737.507248695214;2782.3044238940265;2870.8421036843174;2897.565365195195;3135.357251763383;5204.486971294361;5537.283442132952;5839.795607153729;6158.3841615838055;6245.875082498415;5351.81268655603;5710.8001219957105;6081.756729730761;6130.63216207029;6223.813285601439;5812.372535689929;5885.9405307045145;6194.876102477925;6208.875822483525;6252.374762523802;5358.169483730028;5681.693210561677;5785.56608254391;5798.478136967693;5987.8623061618155;4806.591439727523;5644.6330988485615;5813.970930145491;5923.437273459014;5999.490298175616;5235.554977826928;5641.928033673589;5796;5999.340072592098;6169.413905678954;4344.6089851913475;4656.135407611607;4683.578477936866;4731.195696731495;4731.479537250969;4724.881877953078;5857.619254748428;5961.333779725524;6074.787382441594;6077.513798896166;5316.229146773773;5799.9130013049;5825;5831.300243970751;5836.941630583639;4633.606143477842;5105.693658380509;5665.773369065356;5841.299044114733;5850.853728656818;4807.903841923212;5691.544676425959;5702.173184888251;5771.884562308815;5779.768809247583;5593.468620481048;5745.626534275426;5821.486413532597;5862.648241105548;5873.970630146822;4452.643788496847;5128.128233194931;5635.380108188178;5712.543301458101;5723.5707321950185 11:47:57 INFO - PROCESS | 1641 | |5;yui.html;711.5410560188675;766.0730516075548;795.7254747112245;885.5173930208038;897.3359713811777;16701.665966680674;16956.152192390393;16960.915195424037;17185;17233.96596204227;17080.97514149149;18136.90931545344;18468.722969155475;18507.90746046268;18552.165152568134;985.84719368498;999.8501723018533;1018.836986082227;1029.9742506437362;1035.7100011996654;1008.7427705934977;1018.5823812236985;1020.6988938263192;1023.1252279301202;1023.687775228555;1070.9518071686794;1072.1851392941342;1073.4471747050275;1075.4031512510553;1078.6979645699216;1049.1292227451197;1068.6793961811504;1069.3583849690162;1070.8500809886623;1071.5553045486115;774.5585016540574;784.3920961255016;786.2569871471462;787.8266781308103;789.7117552093445;16858.409955651616;17125.48623541296;17476.650466990588;17509.212085456184;17853.553661158447;13617.319134043259;13813.378397972116;14765.33555989983;14788.334524946296;14994.100353978796;11988.220765650205;12011.699707507294;12027.398630068548;12099.455524501418;12850;3809.2000679856988;3905.7461265017682;3962.722609417342;4002.5397079335958;4050.594940505926;102707;110225;121290.39354803257;121629;122714;11153.94423027896;11180.21738478315;11498.022668073307;11714.121440891797;11740.178187526792;23018.424539386648;23114.53770924606;24775.13287034981;24818;24856.254312370656;8248.505089694638;8264.752057438287;8283.840262363274;8337.166283371736;8339.791505212419;4567.474740404896;4633.606143477775;4684.320773487825;4702.9764851176205;4714.363560919301;3609.061643972586;3666.4500324950936;3705.481232627434;3741.2330472253125;3751.0622344413896;569.4305694305694;629.0846817879974;634.5526403885342;641.7561326695919;648.5622205011598;604.3020311540179;695.7912626212116;701.8034950213948;721.0338146883201;757.5757575757593;9893.708075399602;10450.529726162342;10593.629222977159;10633.255672103034;11099.667009989713;707.8655055539431;719.4352433339803;725.7242247945852;751.8421131562324;762.8931949527071;5073.7716802743225;5156.9742151289;5190.740462976912;5196.012292931579;5201.1678131498775;112319.87680123094;112528.87471125348;113381;113484.43257783659;114257;64848;69522.60954780977;74812.25187748161;84895;89843.55078224567;54666;62720;66255.66872165704;76749.23250767532;80463.59768201239;10929.398883061507;11101;11163.553457861595;11254.7749045017;11359.432028398713;46213.76893115513;46338;51580.19469318556;51803.74098129485;52529.47470525246;66520.05937595281;67348.6632566834;68817;70244.29755702378;70428.29571704217;13132.737345253232;13137.277449740355;13290.933545332211;13445;14341.139531628136;13985.44058237659;15086.867400346118;15698.9215053924;15713.577799801784;16686.49940501787;12801.975841932437;13507.122037067558;13970.511032113827;14091.929540352232;14495.855041449451;13301;14164.929175354056;14866.331015104563;14883.330250138986;15045.398184072952;13139.54011609589;13619.591412257649;13935.30323483842;13970.511032113827;14250.71498570002;9913.950430247802;10356.844647330145;10496.422696751752;10549.947250264007;10859.83710244331;10623.415712135908;11235.382053987109;11474.827877581676;11488.597899073493;11512.539498420305 11:47:57 INFO - PROCESS | 1641 | __end_tp_report 11:47:57 INFO - PROCESS | 1641 | __start_cc_report 11:47:57 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,388 11:47:57 INFO - PROCESS | 1641 | __end_cc_report 11:47:57 INFO - PROCESS | 1641 | __startTimestamp1494874077495__endTimestamp 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | ------- Summary: start ------- 11:47:57 INFO - PROCESS | 1641 | Number of tests: 6 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:6156.40 Median:6300.59 stddev:687.15 (10.9%) stddev-sans-first:643.99 11:47:57 INFO - PROCESS | 1641 | Values: 3029.8 3609.3 3611.7 3634.2 3642.1 6210.7 6220.9 6222.4 6226.7 6237.3 6173.6 6214.7 6231.9 6242.6 6246.1 5607.1 5645.5 5656.9 5664.2 5755.5 5722.9 5747.1 5749.1 5770.0 5776.8 5324.4 5499.0 5538.8 5703.3 5742.7 5621.6 5624.3 5650.2 5666.2 5681.3 5625.0 5691.0 5692.4 5693.1 5733.8 6268.1 6295.5 6303.3 6306.7 6307.3 5674.9 6279.6 6322.0 6430.1 6717.9 6592.8 6624.7 6674.7 6676.2 6686.4 6256.8 6286.7 6293.5 6308.7 6318.3 6638.6 6881.8 6951.6 6971.7 7006.6 6322.3 6324.4 6341.6 6356.4 6367.9 6664.4 6680.2 6694.4 6699.1 6707.3 6272.0 6445.5 6448.1 6481.6 6482.6 6404.4 6516.2 6534.2 6575.9 6603.8 4392.5 5187.3 5237.7 5320.7 5410.7 4795.8 4809.2 4810.9 4818.1 4830.5 6228.6 6237.2 6275.9 6283.1 6312.2 6406.6 6457.6 6596.5 6645.2 6795.6 6289.3 6293.2 6299.1 6300.6 6326.4 5957.0 5979.8 5985.7 6005.8 6006.6 6568.9 6652.3 6825.8 6829.1 6949.0 6790.8 6810.5 6811.4 6811.6 6819.6 6746.3 6893.6 6920.9 6954.8 7015.1 6043.5 6334.9 6529.0 6701.9 6710.9 6639.1 6702.9 6782.3 6784.7 6805.5 6206.5 6555.0 6560.9 6562.3 6564.3 6172.2 6231.6 6285.8 6559.9 6562.4 6148.0 6281.2 6304.1 6316.2 6365.5 5562.0 6532.0 6546.1 6553.6 6557.3 6187.0 6209.7 6282.5 6295.5 6340.8 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:51249.64 Median:30145.12 stddev:99001.44 (328.4%) stddev-sans-first:99206.63 11:47:57 INFO - PROCESS | 1641 | Values: 773.6 822.6 843.2 977.1 978.0 38406.8 39194.4 40951.0 40972.8 41242.6 39369.4 39696.4 39780.8 39843.2 39880.4 8317.5 8434.8 11035.2 11328.3 11549.8 11241.0 11466.4 11471.4 11472.5 11502.6 11450.9 11475.7 11479.8 11488.6 11528.4 11396.9 11427.0 11429.4 11431.8 11432.4 11028.9 11038.9 11105.8 11127.2 11183.7 35523.8 35631.6 35700.1 35731.5 35751.8 3928.2 3973.9 3993.3 4006.4 4077.3 26159.0 26270.0 26419.9 27886.2 28170.4 3238.6 3283.8 3312.8 3318.8 3435.5 337715.0 343806.0 371884.0 374671.0 386212.0 44402.8 44474.6 44479.8 44699.9 44744.1 45402.9 45651.3 45803.2 45829.5 45847.5 14270.0 14395.4 14438.5 14444.8 14460.5 10409.3 12670.5 12716.2 12717.9 12740.0 10037.9 12009.9 12108.8 12156.0 12191.3 5037.4 5608.1 5629.5 5629.7 5645.8 942.9 976.4 978.4 984.4 1001.7 25923.9 26994.2 28033.9 28469.9 28564.9 1062.4 1081.0 1086.3 1109.6 1118.1 12316.8 14654.9 14655.8 14953.3 14992.4 419346.0 423189.0 424230.0 424967.0 425001.0 56410.7 56626.0 56741.7 56933.1 57126.1 1370.6 1490.3 1493.3 1525.4 1535.1 26410.6 27602.2 27632.0 27939.0 28008.7 45454.8 45497.0 45553.1 45665.3 45805.3 330774.0 331960.0 332059.0 332316.0 332440.0 30601.7 31347.5 31433.2 31466.8 32268.8 39037.6 40169.4 41000.8 41306.6 41372.0 21940.0 27268.5 28389.9 28462.4 29185.9 28147.9 28211.9 28291.3 29024.6 29098.3 28832.9 29321.4 33019.5 33172.8 33749.7 27320.0 28880.9 29055.6 29442.4 29688.6 21953.3 23073.8 23229.8 23371.2 23463.4 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:44159.69 Median:22332.44 stddev:131004.61 (586.6%) stddev-sans-first:131330.89 11:47:57 INFO - PROCESS | 1641 | Values: 391.4 418.9 420.4 428.8 462.6 19860.0 20988.4 22204.2 22312.0 22352.9 22196.9 22809.7 23334.5 23657.3 24017.4 6843.6 7027.3 7035.5 7105.0 7110.4 5748.4 6947.8 6981.9 6987.4 7014.9 6655.2 6959.2 6963.7 6992.2 7051.9 6604.6 6899.5 6939.4 6962.5 6964.9 6139.5 6675.2 6742.2 6811.1 6842.5 27599.7 27672.7 27934.4 28077.2 28335.4 2926.9 2945.7 2954.3 2957.2 2963.0 16693.8 17472.4 17664.0 17780.3 17894.7 1841.7 2081.9 2128.4 2151.1 2152.0 124850.0 139152.0 145202.0 152740.0 157456.0 17750.8 20957.2 21442.9 21528.7 21834.1 32638.0 32961.2 33364.0 33476.5 33496.8 8161.9 9911.6 11096.7 11233.0 11250.4 7668.7 9232.4 9239.8 9240.2 9371.9 8797.8 9162.2 9827.9 9966.9 9994.4 3629.6 4199.5 4283.3 4311.5 4314.9 940.9 1078.3 1087.4 1112.9 1121.3 18033.7 21817.5 22583.9 22606.8 22945.0 979.3 1012.3 1102.6 1138.4 1138.8 10849.2 11501.5 11515.9 11566.7 11575.8 780653.0 780722.0 781176.0 792647.0 808092.0 144879.3 151858.0 153646.0 156661.0 160157.0 1265.1 1290.8 1325.2 1326.7 1344.7 16364.8 16694.7 18087.6 19039.2 19983.5 50260.0 56841.4 63951.0 64165.0 65602.7 90763.1 90853.5 92096.5 92552.0 92623.0 11551.9 11792.5 11800.6 11839.6 12132.4 12970.8 12997.7 13037.3 13270.9 13402.9 11425.8 11452.3 11589.8 11632.4 11652.7 10457.6 10544.3 10613.0 10657.8 10781.7 9426.2 10871.0 11617.9 11799.5 11993.5 11603.4 11698.6 11784.5 12029.7 12045.3 9031.3 10095.5 10741.7 11467.0 11471.4 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:7019.09 Median:2537.73 stddev:4340.75 (171.0%) stddev-sans-first:4333.93 11:47:57 INFO - PROCESS | 1641 | Values: 1614.3 1628.8 1649.9 1654.1 1661.1 11103.3 11446.5 11692.2 11734.5 11866.2 11663.9 11813.1 11832.8 11839.9 11854.6 2510.0 2547.9 2550.3 2551.8 2552.2 2482.6 2494.9 2514.4 2517.3 2558.4 2481.3 2494.5 2520.1 2525.1 2527.6 2395.5 2407.1 2408.0 2485.7 2500.7 2480.0 2508.5 2511.3 2512.5 2514.7 11519.2 11703.6 11722.5 11737.5 11763.4 6073.9 6251.6 6257.1 6271.8 6288.6 3595.3 3698.9 3725.6 3745.7 3812.4 1323.8 1944.9 1953.8 1991.7 2029.5 14355.4 14659.6 15609.0 16342.7 16518.9 4853.9 4887.3 5006.8 5056.1 5059.1 12157.0 12163.0 12167.6 12176.9 12179.8 6119.6 6128.1 6141.5 6156.6 6160.5 5475.0 5634.1 5689.5 5690.4 5778.2 3987.4 4100.1 4101.7 4138.3 4181.5 972.0 1101.5 1109.2 1109.4 1121.4 942.6 952.9 964.1 965.9 1006.2 6652.7 6661.7 6710.5 6896.7 6899.7 955.1 995.6 1019.4 1036.0 1060.2 4197.7 4412.2 4460.0 4519.8 4540.5 13745.2 14442.9 14485.4 14496.6 14510.7 14033.3 14288.6 14438.4 14491.4 14522.6 8999.8 9134.0 10664.4 10798.7 10809.2 6297.4 7170.1 7190.9 7303.0 7310.3 12043.9 12142.8 12146.3 12410.8 12445.6 12413.8 12451.9 12656.7 13089.7 13240.5 8624.1 8714.7 9211.6 9500.4 9596.0 10909.1 10961.7 10986.3 10990.8 11077.7 9122.7 9126.8 9165.0 9183.2 9224.5 8121.4 8884.4 8980.4 9018.1 9040.8 8073.7 8128.2 8150.7 8255.5 8270.2 5348.3 5558.6 6052.3 6106.8 6279.2 5224.8 6053.8 6084.8 6135.8 6141.4 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4784.99 Median:5241.33 stddev:1372.43 (26.2%) stddev-sans-first:1335.81 11:47:57 INFO - PROCESS | 1641 | Values: 376.9 383.3 394.8 395.0 401.9 5451.4 5504.5 5617.3 5644.9 5648.4 4720.0 5318.4 5374.6 5436.5 5477.8 3502.7 3658.2 3672.9 3963.0 4122.9 4304.8 4420.9 4481.7 4503.4 4565.7 4118.6 4156.9 4473.5 4482.6 4522.7 3707.3 3936.9 4155.5 4364.5 4474.9 3745.4 3922.5 4084.5 4098.5 4176.8 4399.5 5269.6 5305.4 5317.6 5356.9 4432.7 4656.2 4983.8 5143.8 5227.5 5271.6 5723.7 5771.8 5803.0 5828.9 3868.7 4306.4 4585.4 4614.7 4649.0 5190.4 5348.3 5851.4 5996.4 6170.9 3800.8 4364.4 4704.5 4711.7 4735.8 4277.2 4479.4 4805.4 4816.9 4823.3 4831.7 4890.6 4908.6 5117.3 5206.0 5254.4 5751.3 5764.6 5782.3 5792.9 1336.4 1487.0 1551.9 1635.6 1648.9 1055.9 1083.0 1145.7 1212.1 1239.7 4389.9 5243.2 5436.6 5439.4 5468.3 5177.0 5239.4 5385.6 6016.8 6018.0 5006.3 5367.5 5382.4 5456.2 5461.9 2737.5 2782.3 2870.8 2897.6 3135.4 5204.5 5537.3 5839.8 6158.4 6245.9 5351.8 5710.8 6081.8 6130.6 6223.8 5812.4 5885.9 6194.9 6208.9 6252.4 5358.2 5681.7 5785.6 5798.5 5987.9 4806.6 5644.6 5814.0 5923.4 5999.5 5235.6 5641.9 5796.0 5999.3 6169.4 4344.6 4656.1 4683.6 4731.2 4731.5 4724.9 5857.6 5961.3 6074.8 6077.5 5316.2 5799.9 5825.0 5831.3 5836.9 4633.6 5105.7 5665.8 5841.3 5850.9 4807.9 5691.5 5702.2 5771.9 5779.8 5593.5 5745.6 5821.5 5862.6 5874.0 4452.6 5128.1 5635.4 5712.5 5723.6 11:47:57 INFO - PROCESS | 1641 | 11:47:57 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20966.87 Median:17103.23 stddev:29887.90 (174.8%) stddev-sans-first:29933.05 11:47:57 INFO - PROCESS | 1641 | Values: 711.5 766.1 795.7 885.5 897.3 16701.7 16956.2 16960.9 17185.0 17234.0 17081.0 18136.9 18468.7 18507.9 18552.2 985.8 999.9 1018.8 1030.0 1035.7 1008.7 1018.6 1020.7 1023.1 1023.7 1071.0 1072.2 1073.4 1075.4 1078.7 1049.1 1068.7 1069.4 1070.9 1071.6 774.6 784.4 786.3 787.8 789.7 16858.4 17125.5 17476.7 17509.2 17853.6 13617.3 13813.4 14765.3 14788.3 14994.1 11988.2 12011.7 12027.4 12099.5 12850.0 3809.2 3905.7 3962.7 4002.5 4050.6 102707.0 110225.0 121290.4 121629.0 122714.0 11153.9 11180.2 11498.0 11714.1 11740.2 23018.4 23114.5 24775.1 24818.0 24856.3 8248.5 8264.8 8283.8 8337.2 8339.8 4567.5 4633.6 4684.3 4703.0 4714.4 3609.1 3666.5 3705.5 3741.2 3751.1 569.4 629.1 634.6 641.8 648.6 604.3 695.8 701.8 721.0 757.6 9893.7 10450.5 10593.6 10633.3 11099.7 707.9 719.4 725.7 751.8 762.9 5073.8 5157.0 5190.7 5196.0 5201.2 112319.9 112528.9 113381.0 113484.4 114257.0 64848.0 69522.6 74812.3 84895.0 89843.6 54666.0 62720.0 66255.7 76749.2 80463.6 10929.4 11101.0 11163.6 11254.8 11359.4 46213.8 46338.0 51580.2 51803.7 52529.5 66520.1 67348.7 68817.0 70244.3 70428.3 13132.7 13137.3 13290.9 13445.0 14341.1 13985.4 15086.9 15698.9 15713.6 16686.5 12802.0 13507.1 13970.5 14091.9 14495.9 13301.0 14164.9 14866.3 14883.3 15045.4 13139.5 13619.6 13935.3 13970.5 14250.7 9914.0 10356.8 10496.4 10549.9 10859.8 10623.4 11235.4 11474.8 11488.6 11512.5 11:47:57 INFO - PROCESS | 1641 | -------- Summary: end -------- 11:47:57 INFO - PROCESS | 1641 | 11:47:58 INFO - TEST-INFO | 1641: exit 0 11:47:58 INFO - TEST-OK | dromaeo_css | took 1115747ms 11:47:58 INFO - TEST-START | kraken 11:47:58 INFO - operating with platform_type : mac_ 11:47:58 INFO - Initialising browser for kraken test... 11:47:58 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmp64pUnu/profile http://localhost:49226/getInfo.html) 11:48:00 INFO - PROCESS | 1654 | 2017-05-15 11:48:00.461 plugin-container[1656:10217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 11:48:00 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:00 INFO - PROCESS | 1654 | 2017-05-15 11:48:00.461 plugin-container[1656:10217] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 11:48:00 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:00 INFO - PROCESS | 1654 | 2017-05-15 11:48:00.461 plugin-container[1656:10217] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:48:00 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 11:48:00 INFO - PROCESS | 1654 | colorDepth:24 11:48:00 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 11:48:00 INFO - PROCESS | 1654 | __metrics 11:48:00 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:48:01 INFO - TEST-INFO | 1654: exit 0 11:48:01 INFO - Browser initialized. 11:48:01 INFO - Running cycle 1/1 for kraken test... 11:48:01 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmp64pUnu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:48:03 INFO - PROCESS | 1657 | 2017-05-15 11:48:03.547 plugin-container[1658:10347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 11:48:03 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:03 INFO - PROCESS | 1657 | 2017-05-15 11:48:03.547 plugin-container[1658:10347] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860b, name = 'com.apple.CFPasteboardClient' 11:48:03 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:48:03 INFO - PROCESS | 1657 | 2017-05-15 11:48:03.547 plugin-container[1658:10347] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:48:13 INFO - PROCESS | 1657 | RSS: Main: 174264320 11:48:13 INFO - PROCESS | 1657 | 11:48:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 11:48:17 INFO - PROCESS | 1657 | RSS: Main: 188379136 11:48:17 INFO - PROCESS | 1657 | 11:48:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 11:48:20 INFO - PROCESS | 1657 | RSS: Main: 195870720 11:48:20 INFO - PROCESS | 1657 | 11:48:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 11:48:24 INFO - PROCESS | 1657 | RSS: Main: 199614464 11:48:24 INFO - PROCESS | 1657 | 11:48:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 11:48:27 INFO - PROCESS | 1657 | RSS: Main: 200953856 11:48:27 INFO - PROCESS | 1657 | 11:48:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:48:29 INFO - PROCESS | 1657 | RSS: Main: 201269248 11:48:29 INFO - PROCESS | 1657 | 11:48:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 11:48:33 INFO - PROCESS | 1657 | RSS: Main: 205225984 11:48:33 INFO - PROCESS | 1657 | 11:48:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 11:48:37 INFO - PROCESS | 1657 | RSS: Main: 204865536 11:48:37 INFO - PROCESS | 1657 | 11:48:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 11:48:41 INFO - PROCESS | 1657 | RSS: Main: 205062144 11:48:41 INFO - PROCESS | 1657 | 11:48:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:48:43 INFO - PROCESS | 1657 | RSS: Main: 204701696 11:48:43 INFO - PROCESS | 1657 | 11:48:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:48:45 INFO - PROCESS | 1657 | RSS: Main: 204722176 11:48:45 INFO - PROCESS | 1657 | 11:48:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:48:48 INFO - PROCESS | 1657 | RSS: Main: 204750848 11:48:48 INFO - PROCESS | 1657 | 11:48:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:48:52 INFO - PROCESS | 1657 | RSS: Main: 205156352 11:48:52 INFO - PROCESS | 1657 | 11:48:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:48:55 INFO - PROCESS | 1657 | RSS: Main: 204918784 11:48:55 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 11:48:57 INFO - PROCESS | 1657 | RSS: Main: 207015936 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | __start_tp_report 11:48:57 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 11:48:57 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 11:48:57 INFO - PROCESS | 1657 | |i|pagename|runs| 11:48:57 INFO - PROCESS | 1657 | |0;ai-astar;108;135;158;119;120;122;120;132;176;156 11:48:57 INFO - PROCESS | 1657 | |1;audio-beat-detection;141;120;138;176;112;110;112;112;130;112 11:48:57 INFO - PROCESS | 1657 | |2;audio-dft;197;200;214;235;196;201;200;197;200;197 11:48:57 INFO - PROCESS | 1657 | |3;audio-fft;78;58;61;90;74;60;61;60;73;60 11:48:57 INFO - PROCESS | 1657 | |4;audio-oscillator;84;71;86;72;71;71;81;68;86;109 11:48:57 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;131;91;90;90;101;93;117;91;92;92 11:48:57 INFO - PROCESS | 1657 | |6;imaging-darkroom;98;146;99;107;118;99;99;120;152;140 11:48:57 INFO - PROCESS | 1657 | |7;imaging-desaturate;92;95;93;90;95;92;106;146;94;147 11:48:57 INFO - PROCESS | 1657 | |8;json-parse-financial;64;66;64;64;68;68;68;70;67;70 11:48:57 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;64;69;59;62;55;60;82;81;67;59 11:48:57 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;83;82;86;85;88;83;84;89;88 11:48:57 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;137;135;167;149;150;161;172;201;177 11:48:57 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;166;164;183;222;176;163;167;170;204;170 11:48:57 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;64;69;86;83;83;84;84;70;67;66 11:48:57 INFO - PROCESS | 1657 | __end_tp_report 11:48:57 INFO - PROCESS | 1657 | __start_cc_report 11:48:57 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,985 11:48:57 INFO - PROCESS | 1657 | __end_cc_report 11:48:57 INFO - PROCESS | 1657 | __startTimestamp1494874137984__endTimestamp 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | ------- Summary: start ------- 11:48:57 INFO - PROCESS | 1657 | Number of tests: 14 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:134.60 Median:133.50 stddev:21.76 (16.3%) stddev-sans-first:20.85 11:48:57 INFO - PROCESS | 1657 | Values: 108.0 135.0 158.0 119.0 120.0 122.0 120.0 132.0 176.0 156.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:125.00 stddev:20.98 (16.8%) stddev-sans-first:21.56 11:48:57 INFO - PROCESS | 1657 | Values: 141.0 120.0 138.0 176.0 112.0 110.0 112.0 112.0 130.0 112.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:203.70 Median:200.00 stddev:12.15 (6.1%) stddev-sans-first:12.64 11:48:57 INFO - PROCESS | 1657 | Values: 197.0 200.0 214.0 235.0 196.0 201.0 200.0 197.0 200.0 197.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:67.50 Median:67.00 stddev:10.71 (16.0%) stddev-sans-first:10.67 11:48:57 INFO - PROCESS | 1657 | Values: 78.0 58.0 61.0 90.0 74.0 60.0 61.0 60.0 73.0 60.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:76.50 stddev:12.39 (16.2%) stddev-sans-first:13.05 11:48:57 INFO - PROCESS | 1657 | Values: 84.0 71.0 86.0 72.0 71.0 71.0 81.0 68.0 86.0 109.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:91.00 stddev:14.05 (15.4%) stddev-sans-first:8.83 11:48:57 INFO - PROCESS | 1657 | Values: 131.0 91.0 90.0 90.0 101.0 93.0 117.0 91.0 92.0 92.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:117.80 Median:125.00 stddev:21.16 (16.9%) stddev-sans-first:21.20 11:48:57 INFO - PROCESS | 1657 | Values: 98.0 146.0 99.0 107.0 118.0 99.0 99.0 120.0 152.0 140.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:92.50 stddev:22.30 (24.1%) stddev-sans-first:23.15 11:48:57 INFO - PROCESS | 1657 | Values: 92.0 95.0 93.0 90.0 95.0 92.0 106.0 146.0 94.0 147.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:68.00 stddev:2.33 (3.4%) stddev-sans-first:2.22 11:48:57 INFO - PROCESS | 1657 | Values: 64.0 66.0 64.0 64.0 68.0 68.0 68.0 70.0 67.0 70.0 11:48:57 INFO - PROCESS | 1657 | 11:48:57 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:65.50 stddev:9.22 (14.1%) stddev-sans-first:9.76 11:48:57 INFO - PROCESS | 1657 | Values: 64.0 69.0 59.0 62.0 55.0 60.0 82.0 81.0 67.0 59.0 11:48:58 INFO - PROCESS | 1657 | 11:48:58 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.50 stddev:2.41 (2.8%) stddev-sans-first:2.55 11:48:58 INFO - PROCESS | 1657 | Values: 85.0 83.0 82.0 86.0 85.0 88.0 83.0 84.0 89.0 88.0 11:48:58 INFO - PROCESS | 1657 | 11:48:58 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:158.60 Median:164.00 stddev:21.21 (12.9%) stddev-sans-first:21.01 11:48:58 INFO - PROCESS | 1657 | Values: 137.0 137.0 135.0 167.0 149.0 150.0 161.0 172.0 201.0 177.0 11:48:58 INFO - PROCESS | 1657 | 11:48:58 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:173.00 stddev:19.59 (11.3%) stddev-sans-first:20.25 11:48:58 INFO - PROCESS | 1657 | Values: 166.0 164.0 183.0 222.0 176.0 163.0 167.0 170.0 204.0 170.0 11:48:58 INFO - PROCESS | 1657 | 11:48:58 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.60 Median:83.00 stddev:9.03 (10.9%) stddev-sans-first:8.55 11:48:58 INFO - PROCESS | 1657 | Values: 64.0 69.0 86.0 83.0 83.0 84.0 84.0 70.0 67.0 66.0 11:48:58 INFO - PROCESS | 1657 | -------- Summary: end -------- 11:48:58 INFO - PROCESS | 1657 | 11:48:58 INFO - TEST-INFO | 1657: exit 0 11:48:58 INFO - TEST-OK | kraken | took 60944ms 11:48:58 INFO - SUITE-END | took 1176s 11:48:59 INFO - Completed test suite (00:19:36) 11:48:59 INFO - javascript benchmark 11:48:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7947.280806171687, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3029.757619390449, 3609.3142302962433, 3611.6568925952024, 3634.2368102698433, 3642.1258897864504, 6210.689465526728, 6220.906686399708, 6222.377762223776, 6226.657533835643, 6237.313895471494, 6173.567850250484, 6214.65819379934, 6231.875362492748, 6242.625442473455, 6246.063090536421, 5607.130894711327, 5645.491905728483, 5656.858578535528, 5664.20701101846, 5755.45323194298, 5722.942770572283, 5747.137929310595, 5749.080147176453, 5770.0, 5776.826695199151, 5324.414314425429, 5499.037668408033, 5538.806141785039, 5703.287089113861, 5742.655440673552, 5621.606487545873, 5624.3250809903075, 5650.237217975603, 5666.206731057655, 5681.261436013333, 5625.0, 5691.03252447085, 5692.430756924316, 5693.089105743061, 5733.827985160452, 6268.122462855204, 6295.527835412362, 6303.338149494283, 6306.747730090791, 6307.337729538378, 5674.8933957878235, 6279.591826531261, 6322.0, 6430.1319321891415, 6717.899231511531, 6592.76925307612, 6624.701888415033, 6674.666266686695, 6676.198856137295, 6686.3982241598005, 6256.812295631138, 6286.654234017127, 6293.465055470337, 6308.653024083674, 6318.2733985591785, 6638.5684930479365, 6881.759138430131, 6951.582905025714, 6971.721131154697, 7006.649667516604, 6322.304546499881, 6324.43080122782, 6341.587796793194, 6356.396142366468, 6367.872642547123, 6664.433523150489, 6680.19837619479, 6694.397504224643, 6699.095622091053, 6707.295733947865, 6272.027835685385, 6445.484361251089, 6448.065030570541, 6481.643509606923, 6482.57863238888, 6404.423601875854, 6516.152900122954, 6534.248561415403, 6575.868482630321, 6603.7688680895935, 4392.538783427694, 5187.299714538489, 5237.738113094406, 5320.733963301819, 5410.702411367334, 4795.808167673324, 4809.206480930678, 4810.927836082462, 4818.084563932842, 4830.541098595628, 6228.56400051992, 6237.220347456568, 6275.874482510324, 6283.120363149163, 6312.210973628296, 6406.551541392151, 6457.61254324742, 6596.505262105361, 6645.169353830771, 6795.558288711317, 6289.308176100625, 6293.244810622755, 6299.055141728686, 6300.59046161998, 6326.430621244018, 5957.017092179742, 5979.790707325223, 5985.71692359896, 6005.849853753691, 6006.579539432285, 6568.86862262752, 6652.334766523309, 6825.761098361533, 6829.146356705412, 6949.0, 6790.7623233186605, 6810.523263371517, 6811.352921472452, 6811.625360605016, 6819.624920629413, 6746.291639377991, 6893.620850853251, 6920.9307906920285, 6954.756583519755, 7015.123109611299, 6043.516518678407, 6334.904976425265, 6529.0, 6701.899471507833, 6710.932890671226, 6639.070530125689, 6702.932970670231, 6782.287859774653, 6784.6946887389195, 6805.523613347216, 6206.510437495059, 6555.016747487724, 6560.9343906562235, 6562.343765623399, 6564.277929427663, 6172.19761431029, 6231.594946328474, 6285.811425657238, 6559.934400655932, 6562.409383155539, 6148.0, 6281.183446151971, 6304.117423560613, 6316.242050953915, 6365.522585806176, 5562.0, 6532.0, 6546.0508226305965, 6553.606783593, 6557.311482294481, 6186.969065154645, 6209.65846878408, 6282.497400207881, 6295.464885484602, 6340.841478963062], "value": 6111.920239220291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.5783997721242, 822.6298165825378, 843.2073850580456, 977.0669011094406, 978.0366339155935, 38406.807965960164, 39194.41208381873, 40951.0, 40972.770816875505, 41242.58757412425, 39369.40945885814, 39696.404553931716, 39780.80109599455, 39843.20313593726, 39880.40179397304, 8317.500949943007, 8434.789130271762, 11035.172362072837, 11328.320300781937, 11549.826752598718, 11241.0, 11466.369349685763, 11471.426428678575, 11472.4837382318, 11502.597409090684, 11450.854914508565, 11475.655730328104, 11479.770404591904, 11488.597899073535, 11528.365939873303, 11396.886031139748, 11427.0, 11429.428528573539, 11431.828522572127, 11432.42837858112, 11028.944855275753, 11038.944805276002, 11105.777884442266, 11127.165462590337, 11183.664490065312, 35523.82238088793, 35631.64368356309, 35700.107497312514, 35731.4640280396, 35751.82124089389, 3928.2143571285856, 3973.940390894139, 3993.2612466693463, 4006.4390985262085, 4077.2864748669163, 26159.0, 26270.0, 26419.867900660567, 27886.163415097377, 28170.436591268266, 3238.5951756030495, 3283.753718471124, 3312.801231926068, 3318.767686261963, 3435.484677298425, 337715.0, 343806.0, 371884.0, 374671.0, 386212.0, 44402.77798610986, 44474.55525444704, 44479.77760111179, 44699.882502937035, 44744.10511789746, 45402.864928376395, 45651.315230271575, 45803.16787328469, 45829.54170458319, 45847.541524584325, 14270.001099922905, 14395.42418303277, 14438.494652687315, 14444.783328250085, 14460.493882714054, 10409.271351005356, 12670.49318027287, 12716.237025778299, 12717.872821271669, 12740.044496662787, 10037.899621003697, 12009.879901200877, 12108.81836772449, 12156.0, 12191.329476878856, 5037.370328708911, 5608.130739735266, 5629.521490673339, 5629.746661400247, 5645.774169033275, 942.8585712143097, 976.3556053005125, 978.3885071829964, 984.3995162950595, 1001.7245257554231, 25923.870380648354, 26994.190174294803, 28033.859830701123, 28469.857650711612, 28564.857175713987, 1062.4369084385135, 1080.9621663241749, 1086.2613422872523, 1109.6227282723912, 1118.0664145438486, 12316.81524777129, 14654.926725366306, 14655.780163297559, 14953.252337383088, 14992.400303987719, 419346.0, 423189.0, 424230.0, 424967.0, 425001.0, 56410.717946410005, 56626.0, 56741.71629141828, 56933.14600280999, 57126.143107852586, 1370.6230786533783, 1490.2846633615925, 1493.2757612558119, 1525.4432132271863, 1535.08662345904, 26410.603840942018, 27602.171934841987, 27632.032878849142, 27939.0, 28008.71991280061, 45454.77272613616, 45497.0, 45553.08893822039, 45665.31502027406, 45805.31292030556, 330774.0, 331960.0, 332059.0, 332316.0, 332440.0, 30601.693983060773, 31347.529787053667, 31433.21416964594, 31466.842665786524, 32268.838655806572, 39037.609623903394, 40169.39745903872, 41000.79499602602, 41306.586934130275, 41372.0, 21940.0, 27268.45463090767, 28389.858050709616, 28462.430751384443, 29185.85407072951, 28147.859260703564, 28211.858940705984, 28291.292717682223, 29024.56463153012, 29098.27254318659, 28832.855835720686, 29321.41357172802, 33019.504707429885, 33172.834135829165, 33749.66250337465, 27320.0, 28880.85559572189, 29055.56416653794, 29442.411151777273, 29688.554671680373, 21953.341399758032, 23073.769262307163, 23229.76770232276, 23371.182008630298, 23463.41341466477], "value": 18381.254079528742, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.39724823771394, 418.93925380819803, 420.4156222850237, 428.8370419240689, 462.58136386570146, 19860.0, 20988.370348889537, 22204.222852200135, 22312.0, 22352.88823555882, 22196.8901554922, 22809.657855132187, 23334.533309333845, 23657.290281291545, 24017.438866473665, 6843.589384636925, 7027.262137475568, 7035.5075144739885, 7105.040819489354, 7110.360067593915, 5748.367679555246, 6947.756828511003, 6981.860362792741, 6987.441004719635, 7014.859702805941, 6655.168103987002, 6959.234484206733, 6963.721451141948, 6992.19589747178, 7051.89422158668, 6604.603723776589, 6899.482538809584, 6939.444844412435, 6962.547434416772, 6964.930350696504, 6139.508839292846, 6675.232348279961, 6742.190937087532, 6811.114555107853, 6842.521023528355, 27599.724002759915, 27672.723272767416, 27934.44131117366, 28077.157685269474, 28335.43329133406, 2926.8682909269132, 2945.7054294570585, 2954.3205062835455, 2957.1719918422878, 2963.0370129707717, 16693.83306166935, 17472.388466403743, 17664.0, 17780.288788448448, 17894.731579026324, 1841.6777064013745, 2081.948615948936, 2128.4040468668795, 2151.107290474451, 2152.042341158185, 124850.0, 139152.0, 145202.0, 152740.0, 157456.0, 17750.822491774918, 20957.22769901402, 21442.892785536285, 21528.677069843965, 21834.126634934742, 32638.0, 32961.175970600925, 33364.0, 33476.49785253223, 33496.83251583727, 8161.918380816234, 9911.603535858485, 11096.722581935515, 11233.0, 11250.437478126061, 7668.693252269847, 9232.446053236694, 9239.815203695889, 9240.168384845396, 9371.906280937103, 8797.780055498535, 9162.175404213653, 9827.901720982698, 9966.900330996743, 9994.350367226107, 3629.564452265745, 4199.517055538591, 4283.265277562592, 4311.48262208537, 4314.870553883388, 940.8870935487648, 1078.3260462211117, 1087.3856271206744, 1112.933224006562, 1121.3216004317271, 18033.7294940576, 21817.454563636034, 22583.87080645961, 22606.773932260796, 22945.0, 979.314479864098, 1012.2610494339026, 1102.597551893569, 1138.3795831271811, 1138.7950168969498, 10849.186311026546, 11501.482433290523, 11515.942420287845, 11566.653000409833, 11575.76848463026, 780653.0, 780722.0, 781176.0, 792647.0, 808092.0, 144879.2756036213, 151858.0, 153646.0, 156661.0, 160157.0, 1265.0828149591473, 1290.7934730443085, 1325.2313658078099, 1326.7412854493282, 1344.6907211341447, 16364.75452868184, 16694.749578756084, 18087.638247234718, 19039.237913410583, 19983.500412489804, 50260.0, 56841.43158568361, 63951.0, 64165.0, 65602.67198663976, 90763.0923690781, 90853.54573227356, 92096.53951730198, 92552.0, 92623.0, 11551.884481155417, 11792.52829886795, 11800.586979455678, 11839.644810655349, 12132.393380331125, 12970.80543791825, 12997.675058123623, 13037.282949437871, 13270.867291326964, 13402.93298533501, 11425.828612570984, 11452.312861228353, 11589.82615260755, 11632.360220187971, 11652.708682283012, 10457.60121467604, 10544.314619549705, 10613.0, 10657.840132397865, 10781.676549703521, 9426.198773104088, 10871.0, 11617.941910290732, 11799.52801887915, 11993.520259189534, 11603.419829008684, 11698.590549330733, 11784.469698863742, 12029.699257518632, 12045.33750643688, 9031.322650801347, 10095.545700443645, 10741.677749667522, 11467.0, 11471.426428678365], "value": 11817.142970678695, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1614.2735768903997, 1628.8126865410482, 1649.8845080844346, 1654.106782337538, 1661.0615002523573, 11103.278286911345, 11446.48490817913, 11692.240004399708, 11734.530618775249, 11866.22869513481, 11663.941680291608, 11813.114016448757, 11832.822507662391, 11839.940800296008, 11854.585089521868, 2510.0, 2547.898084076635, 2550.2859199424192, 2551.808614353931, 2552.1577879299784, 2482.615194644833, 2494.9396506484763, 2514.358838496181, 2517.34549017255, 2558.3859873630286, 2481.3424442522746, 2494.5260400523935, 2520.143151328548, 2525.103588226181, 2527.5703130467864, 2395.5448464791634, 2407.121400688736, 2407.9759202408013, 2485.7389974052735, 2500.6874140732407, 2480.0, 2508.4857604191097, 2511.3344963584664, 2512.497500499889, 2514.660520829701, 11519.193656444053, 11703.590374336858, 11722.472488738027, 11737.53049878004, 11763.35301558414, 6073.908891366633, 6251.593646412968, 6257.092721555349, 6271.843203919893, 6288.62268263901, 3595.26297109092, 3698.9260214795554, 3725.590185079639, 3745.7190710696536, 3812.370958791796, 1323.7948118041622, 1944.922203111888, 1953.765548134233, 1991.6813309870352, 2029.482481967089, 14355.425782968668, 14659.560213193621, 15609.0, 16342.673146537003, 16518.917405413016, 4853.854384368475, 4887.29134275528, 5006.849794506171, 5056.06462804375, 5059.114654935372, 12157.0, 12163.02695784335, 12167.634970950885, 12176.939115304544, 12179.75640487203, 6119.632822030692, 6128.111423843607, 6141.508679305645, 6156.5998210116195, 6160.507159427235, 5474.987127381447, 5634.098544232903, 5689.544836413077, 5690.430956904359, 5778.219940308089, 3987.441758153861, 4100.097978444737, 4101.651359634405, 4138.296489596776, 4181.51912530063, 971.9805603887883, 1101.498818037791, 1109.2457129152253, 1109.4341885638382, 1121.4224674292745, 942.5569982108398, 952.9380590261611, 964.1226483899618, 965.8985806490217, 1006.1642473652785, 6652.70062847173, 6661.700223489857, 6710.463162946952, 6896.724131034803, 6899.724011039602, 955.1212884146602, 995.576879826071, 1019.4393083803878, 1035.9689209323733, 1060.1518784972145, 4197.685173611931, 4412.205802955435, 4460.018795864905, 4519.751413672214, 4540.455145382575, 13745.175289482662, 14442.927785361006, 14485.420583176765, 14496.637584060272, 14510.709785804225, 14033.298335083206, 14288.642783930485, 14438.350274237684, 14491.420343186366, 14522.564323070113, 8999.82000359989, 9134.0, 10664.413457259925, 10798.67603971882, 10809.189310801721, 6297.370262973666, 7170.103737032871, 7190.928090719235, 7303.0, 7310.342069213796, 12043.879561204276, 12142.817857732318, 12146.331951742739, 12410.75178496443, 12445.564405245774, 12413.813792792935, 12451.879330860265, 12656.6835829105, 13089.738205235652, 13240.53658121961, 8624.09447008055, 8714.738557843275, 9211.631534738535, 9500.42997420157, 9595.952020239854, 10909.127269818237, 10961.671149865198, 10986.285891417032, 10990.780184396428, 11077.667669969915, 9122.726318210463, 9126.817463650823, 9164.954175229348, 9183.173514383738, 9224.538773061186, 8121.431499794958, 8884.422512536665, 8980.416272942499, 9018.143276388733, 9040.773980650536, 8073.717419890276, 8128.187181281824, 8150.7147249845975, 8255.504669719836, 8270.214329638675, 5348.251244825649, 5558.610897237155, 6052.273727152769, 6106.755729770759, 6279.215098112736, 5224.843254702365, 6053.818385448443, 6084.817455476343, 6135.815925522242, 6141.447269745744], "value": 5408.560107657656, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.9340365436048, 383.327260657546, 394.77300552182504, 394.99407508887356, 401.90957034667196, 5451.373092094401, 5504.477074677904, 5617.269754931858, 5644.915326270109, 5648.378678345389, 4719.976400117995, 5318.3939869463875, 5374.6237763356585, 5436.510714035736, 5477.75350109245, 3502.7197824174004, 3658.1951970566433, 3672.926541469182, 3962.988129021935, 4122.876313710593, 4304.816175551717, 4420.911581768362, 4481.731096134243, 4503.378783637888, 4565.680402371835, 4118.6499147572495, 4156.877643036379, 4473.463184417875, 4482.61897738691, 4522.683412161134, 3707.258548290353, 3936.881893543198, 4155.542890282067, 4364.49808272048, 4474.910501789962, 3745.419459983707, 3922.509446410346, 4084.530279017921, 4098.4876890388705, 4176.809609261346, 4399.450068741407, 5269.552088072518, 5305.4164041955355, 5317.6174194709265, 5356.90183512379, 4432.667549933735, 4656.231721765905, 4983.8006479741125, 5143.7685304161405, 5227.457899919533, 5271.604629652791, 5723.742431590588, 5771.797987070474, 5802.955468015755, 5828.854278643068, 3868.6905047596124, 4306.3755755415605, 4585.449746030497, 4614.653900957442, 4648.976755116237, 5190.429052804227, 5348.331458567679, 5851.38560451155, 5996.430339117734, 6170.907436388457, 3800.771953682788, 4364.410804541346, 4704.458987216514, 4711.74085425298, 4735.83424580138, 4277.187334406515, 4479.440069991251, 4805.399325084364, 4816.903661926742, 4823.335949097626, 4831.661783675179, 4890.559849613481, 4908.631852611068, 5117.334746482933, 5206.0, 5254.395744489356, 5751.281089863767, 5764.645308352534, 5782.277215348082, 5792.884142317214, 1336.4453751693134, 1486.9925650371895, 1551.8680912122595, 1635.623806524506, 1648.8705236912754, 1055.889131641182, 1083.009046722256, 1145.6505765741529, 1212.0727643352843, 1239.6983167673907, 4389.934150987738, 5243.217306115863, 5436.646617969819, 5439.428859969726, 5468.316460442445, 5177.0, 5239.449857764956, 5385.649932754359, 6016.759329626853, 6018.007028840192, 5006.299118123466, 5367.463253674601, 5382.3541175059245, 5456.236126942231, 5461.9453805462235, 2737.507248695214, 2782.3044238940265, 2870.8421036843174, 2897.565365195195, 3135.357251763383, 5204.486971294361, 5537.283442132952, 5839.795607153729, 6158.3841615838055, 6245.875082498415, 5351.81268655603, 5710.8001219957105, 6081.756729730761, 6130.63216207029, 6223.813285601439, 5812.372535689929, 5885.9405307045145, 6194.876102477925, 6208.875822483525, 6252.374762523802, 5358.169483730028, 5681.693210561677, 5785.56608254391, 5798.478136967693, 5987.8623061618155, 4806.591439727523, 5644.6330988485615, 5813.970930145491, 5923.437273459014, 5999.490298175616, 5235.554977826928, 5641.928033673589, 5796.0, 5999.340072592098, 6169.413905678954, 4344.6089851913475, 4656.135407611607, 4683.578477936866, 4731.195696731495, 4731.479537250969, 4724.881877953078, 5857.619254748428, 5961.333779725524, 6074.787382441594, 6077.513798896166, 5316.229146773773, 5799.9130013049, 5825.0, 5831.300243970751, 5836.941630583639, 4633.606143477842, 5105.693658380509, 5665.773369065356, 5841.299044114733, 5850.853728656818, 4807.903841923212, 5691.544676425959, 5702.173184888251, 5771.884562308815, 5779.768809247583, 5593.468620481048, 5745.626534275426, 5821.486413532597, 5862.648241105548, 5873.970630146822, 4452.643788496847, 5128.128233194931, 5635.380108188178, 5712.543301458101, 5723.5707321950185], "value": 4380.663457687201, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.5410560188675, 766.0730516075548, 795.7254747112245, 885.5173930208038, 897.3359713811777, 16701.665966680674, 16956.152192390393, 16960.915195424037, 17185.0, 17233.96596204227, 17080.97514149149, 18136.90931545344, 18468.722969155475, 18507.90746046268, 18552.165152568134, 985.84719368498, 999.8501723018533, 1018.836986082227, 1029.9742506437362, 1035.7100011996654, 1008.7427705934977, 1018.5823812236985, 1020.6988938263192, 1023.1252279301202, 1023.687775228555, 1070.9518071686794, 1072.1851392941342, 1073.4471747050275, 1075.4031512510553, 1078.6979645699216, 1049.1292227451197, 1068.6793961811504, 1069.3583849690162, 1070.8500809886623, 1071.5553045486115, 774.5585016540574, 784.3920961255016, 786.2569871471462, 787.8266781308103, 789.7117552093445, 16858.409955651616, 17125.48623541296, 17476.650466990588, 17509.212085456184, 17853.553661158447, 13617.319134043259, 13813.378397972116, 14765.33555989983, 14788.334524946296, 14994.100353978796, 11988.220765650205, 12011.699707507294, 12027.398630068548, 12099.455524501418, 12850.0, 3809.2000679856988, 3905.7461265017682, 3962.722609417342, 4002.5397079335958, 4050.594940505926, 102707.0, 110225.0, 121290.39354803257, 121629.0, 122714.0, 11153.94423027896, 11180.21738478315, 11498.022668073307, 11714.121440891797, 11740.178187526792, 23018.424539386648, 23114.53770924606, 24775.13287034981, 24818.0, 24856.254312370656, 8248.505089694638, 8264.752057438287, 8283.840262363274, 8337.166283371736, 8339.791505212419, 4567.474740404896, 4633.606143477775, 4684.320773487825, 4702.9764851176205, 4714.363560919301, 3609.061643972586, 3666.4500324950936, 3705.481232627434, 3741.2330472253125, 3751.0622344413896, 569.4305694305694, 629.0846817879974, 634.5526403885342, 641.7561326695919, 648.5622205011598, 604.3020311540179, 695.7912626212116, 701.8034950213948, 721.0338146883201, 757.5757575757593, 9893.708075399602, 10450.529726162342, 10593.629222977159, 10633.255672103034, 11099.667009989713, 707.8655055539431, 719.4352433339803, 725.7242247945852, 751.8421131562324, 762.8931949527071, 5073.7716802743225, 5156.9742151289, 5190.740462976912, 5196.012292931579, 5201.1678131498775, 112319.87680123094, 112528.87471125348, 113381.0, 113484.43257783659, 114257.0, 64848.0, 69522.60954780977, 74812.25187748161, 84895.0, 89843.55078224567, 54666.0, 62720.0, 66255.66872165704, 76749.23250767532, 80463.59768201239, 10929.398883061507, 11101.0, 11163.553457861595, 11254.7749045017, 11359.432028398713, 46213.76893115513, 46338.0, 51580.19469318556, 51803.74098129485, 52529.47470525246, 66520.05937595281, 67348.6632566834, 68817.0, 70244.29755702378, 70428.29571704217, 13132.737345253232, 13137.277449740355, 13290.933545332211, 13445.0, 14341.139531628136, 13985.44058237659, 15086.867400346118, 15698.9215053924, 15713.577799801784, 16686.49940501787, 12801.975841932437, 13507.122037067558, 13970.511032113827, 14091.929540352232, 14495.855041449451, 13301.0, 14164.929175354056, 14866.331015104563, 14883.330250138986, 15045.398184072952, 13139.54011609589, 13619.591412257649, 13935.30323483842, 13970.511032113827, 14250.71498570002, 9913.950430247802, 10356.844647330145, 10496.422696751752, 10549.947250264007, 10859.83710244331, 10623.415712135908, 11235.382053987109, 11474.827877581676, 11488.597899073493, 11512.539498420305], "value": 8009.165883032574, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1564.2999999999995, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 135.0, 158.0, 119.0, 120.0, 122.0, 120.0, 132.0, 176.0, 156.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 120.0, 138.0, 176.0, 112.0, 110.0, 112.0, 112.0, 130.0, 112.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 214.0, 235.0, 196.0, 201.0, 200.0, 197.0, 200.0, 197.0], "value": 203.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 61.0, 90.0, 74.0, 60.0, 61.0, 60.0, 73.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 86.0, 72.0, 71.0, 71.0, 81.0, 68.0, 86.0, 109.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 91.0, 90.0, 90.0, 101.0, 93.0, 117.0, 91.0, 92.0, 92.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 146.0, 99.0, 107.0, 118.0, 99.0, 99.0, 120.0, 152.0, 140.0], "value": 117.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 93.0, 90.0, 95.0, 92.0, 106.0, 146.0, 94.0, 147.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 64.0, 68.0, 68.0, 68.0, 70.0, 67.0, 70.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 59.0, 62.0, 55.0, 60.0, 82.0, 81.0, 67.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 86.0, 85.0, 88.0, 83.0, 84.0, 89.0, 88.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 135.0, 167.0, 149.0, 150.0, 161.0, 172.0, 201.0, 177.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 183.0, 222.0, 176.0, 163.0, 167.0, 170.0, 204.0, 170.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 86.0, 83.0, 83.0, 84.0, 84.0, 70.0, 67.0, 66.0], "value": 75.6, "unit": "score"}]}]} 11:48:59 INFO - Return code: 0 11:48:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:48:59 ERROR - # TBPL SUCCESS # 11:48:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:48:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:48:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:48:59 INFO - 2017-05-15 11:48:59.333 screenresolution[1662:10740] starting screenresolution argv=screenresolution get 11:48:59 INFO - 2017-05-15 11:48:59.336 screenresolution[1662:10740] Display 0: 1600x1200x32@60 11:48:59 INFO - 2017-05-15 11:48:59.342 screenresolution[1663:10743] starting screenresolution argv=screenresolution list 11:48:59 INFO - Available Modes on Display 0 11:48:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:48:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:48:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:48:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:48:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:48:59 INFO - Intel Iris: 11:48:59 INFO - Chipset Model: Intel Iris 11:48:59 INFO - Type: GPU 11:48:59 INFO - Bus: Built-In 11:48:59 INFO - VRAM (Dynamic, Max): 1536 MB 11:48:59 INFO - Vendor: Intel (0x8086) 11:48:59 INFO - Device ID: 0x0a2e 11:48:59 INFO - Revision ID: 0x0009 11:48:59 INFO - Displays: 11:48:59 INFO - Display: 11:48:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:48:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:48:59 INFO - Display Serial Number: 1600x1200 60 11:48:59 INFO - Main Display: Yes 11:48:59 INFO - Mirror: Off 11:48:59 INFO - Online: Yes 11:48:59 INFO - Rotation: Supported 11:48:59 INFO - Return code: 0 11:48:59 INFO - Running post-action listener: _resource_record_post_action 11:48:59 INFO - [mozharness: 2017-05-15 18:48:59.580221Z] Finished run-tests step (success) 11:48:59 INFO - Running post-run listener: _resource_record_post_run 11:48:59 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 40794112; Write bytes: 427821056; Read time: 393; Write time: 1512 11:48:59 INFO - TinderboxPrint: CPU usage
54.9% 11:48:59 INFO - TinderboxPrint: I/O read bytes / time
40,794,112 / 393 11:48:59 INFO - TinderboxPrint: I/O write bytes / time
427,821,056 / 1,512 11:48:59 INFO - TinderboxPrint: CPU idle
2,149.3 (45.1%) 11:48:59 INFO - TinderboxPrint: CPU system
962.3 (20.2%) 11:48:59 INFO - TinderboxPrint: CPU user
1,658.1 (34.8%) 11:48:59 INFO - TinderboxPrint: Swap in / out
163,250,176 / 0 11:48:59 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119579136; Write bytes: 117696512; Read time: 10907; Write time: 312 11:48:59 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 38721536; Write bytes: 299253760; Read time: 366; Write time: 1162 11:49:00 INFO - Running post-run listener: _upload_blobber_files 11:49:00 INFO - Blob upload gear active. 11:49:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:49:00 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:49:00 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:49:00 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:49:00 INFO - (blobuploader) - INFO - Open directory for files ... 11:49:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:49:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:49:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:01 INFO - (blobuploader) - INFO - Done attempting. 11:49:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:49:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:49:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:02 INFO - (blobuploader) - INFO - Done attempting. 11:49:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:49:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:03 INFO - (blobuploader) - INFO - Done attempting. 11:49:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:49:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:49:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:49:06 INFO - (blobuploader) - INFO - Done attempting. 11:49:06 INFO - (blobuploader) - INFO - Iteration through files over. 11:49:06 INFO - Return code: 0 11:49:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:49:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:49:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f4f72d9a62ef44e1aa6ea5cd3d4723d1576aa646a76e47456581e4cb4f62873619aeafba17a179e3a2dab424748bc2a10a14603fd61358ca0af57e4df22bdc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4809a5c4a3bfa33cc1575925be8508b8aa64f16f6c37b49d0b429e1327f1b08dd58f7c8e7c0c2f3ccf79507cee65c45a367081a0020a73667a9af3c08946fd05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bf1d19a9c49f0cde134dbd5f9ce4acc92217fb30e71c04ee187420795cc20de8639c1d4980c119300cb60d620fdaf5f86ad8ad095d0ca074a2236cd63289fcd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aa01cc55e07b00334bb1254d7666d0c601785a9e24b0173c9ce0eda2ccdda63d94b7f7c91906d72bf80f43595fc65d109ba77e14576f539cf8cc53c185cb73"} 11:49:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:49:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:49:06 INFO - Contents: 11:49:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f4f72d9a62ef44e1aa6ea5cd3d4723d1576aa646a76e47456581e4cb4f62873619aeafba17a179e3a2dab424748bc2a10a14603fd61358ca0af57e4df22bdc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4809a5c4a3bfa33cc1575925be8508b8aa64f16f6c37b49d0b429e1327f1b08dd58f7c8e7c0c2f3ccf79507cee65c45a367081a0020a73667a9af3c08946fd05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bf1d19a9c49f0cde134dbd5f9ce4acc92217fb30e71c04ee187420795cc20de8639c1d4980c119300cb60d620fdaf5f86ad8ad095d0ca074a2236cd63289fcd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aa01cc55e07b00334bb1254d7666d0c601785a9e24b0173c9ce0eda2ccdda63d94b7f7c91906d72bf80f43595fc65d109ba77e14576f539cf8cc53c185cb73"} 11:49:06 INFO - Running post-run listener: copy_logs_to_upload_dir 11:49:06 INFO - Copying logs to upload dir... 11:49:06 INFO - mkdir: /builds/slave/test/build/upload/logs 11:49:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.814723 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 11:49:06.508083) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:06.512366) ========= 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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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/0f4f72d9a62ef44e1aa6ea5cd3d4723d1576aa646a76e47456581e4cb4f62873619aeafba17a179e3a2dab424748bc2a10a14603fd61358ca0af57e4df22bdc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4809a5c4a3bfa33cc1575925be8508b8aa64f16f6c37b49d0b429e1327f1b08dd58f7c8e7c0c2f3ccf79507cee65c45a367081a0020a73667a9af3c08946fd05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bf1d19a9c49f0cde134dbd5f9ce4acc92217fb30e71c04ee187420795cc20de8639c1d4980c119300cb60d620fdaf5f86ad8ad095d0ca074a2236cd63289fcd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aa01cc55e07b00334bb1254d7666d0c601785a9e24b0173c9ce0eda2ccdda63d94b7f7c91906d72bf80f43595fc65d109ba77e14576f539cf8cc53c185cb73"} build_url:https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/PnYQEnQ5TtmfZSfCv2Muvg/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/0f4f72d9a62ef44e1aa6ea5cd3d4723d1576aa646a76e47456581e4cb4f62873619aeafba17a179e3a2dab424748bc2a10a14603fd61358ca0af57e4df22bdc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4809a5c4a3bfa33cc1575925be8508b8aa64f16f6c37b49d0b429e1327f1b08dd58f7c8e7c0c2f3ccf79507cee65c45a367081a0020a73667a9af3c08946fd05", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4bf1d19a9c49f0cde134dbd5f9ce4acc92217fb30e71c04ee187420795cc20de8639c1d4980c119300cb60d620fdaf5f86ad8ad095d0ca074a2236cd63289fcd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16aa01cc55e07b00334bb1254d7666d0c601785a9e24b0173c9ce0eda2ccdda63d94b7f7c91906d72bf80f43595fc65d109ba77e14576f539cf8cc53c185cb73"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:06.551076) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:06.551414) ========= 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.1D3tOeDXVk/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.IE6ewPVxw0/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.004537 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:49:06.600079) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:49:06.600403) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:49:06.600767) ========= ========= Total master_lag: 0.18 =========