builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0303 starttime: 1495225837.14 results: success (0) buildid: 20170519115822 builduid: 0fb720275bd54dc9a76655a97ef3555b revision: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.144798) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.145314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.145606) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.170856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.171249) ========= 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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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-19 13:30:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.2M=0.001s 2017-05-19 13:30:37 (19.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142133 ========= master_lag: 0.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.484249) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.484647) ========= 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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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.027762 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:37.539027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-19 13:30:37.539397) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0241994e3aad3594cae0d42c7d80a2dff7b245e8 --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 0241994e3aad3594cae0d42c7d80a2dff7b245e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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-19 13:30:37,814 truncating revision to first 12 chars 2017-05-19 13:30:37,814 Setting DEBUG logging. 2017-05-19 13:30:37,815 attempt 1/10 2017-05-19 13:30:37,815 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0241994e3aad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 13:30:39,251 unpacking tar archive at: mozilla-beta-0241994e3aad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.714693 ========= master_lag: 1.88 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-19 13:30:41.131651) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:41.132249) ========= script_repo_revision: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:41.132653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:41.132943) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 13:30:41.160396) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-19 13:30:41.160736) ========= /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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:30:41 INFO - MultiFileLogger online at 20170519 13:30:41 in /builds/slave/test 13:30:41 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 13:30:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:30:41 INFO - {'append_to_log': False, 13:30:41 INFO - 'base_work_dir': '/builds/slave/test', 13:30:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:30:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:30:41 INFO - 'branch': 'Mozilla-Beta', 13:30:41 INFO - 'buildbot_json_path': 'buildprops.json', 13:30:41 INFO - 'config_files': ('talos/mac_config.py',), 13:30:41 INFO - 'default_actions': ('clobber', 13:30:41 INFO - 'read-buildbot-config', 13:30:41 INFO - 'download-and-extract', 13:30:41 INFO - 'populate-webroot', 13:30:41 INFO - 'create-virtualenv', 13:30:41 INFO - 'install', 13:30:41 INFO - 'run-tests'), 13:30:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:30:41 INFO - 'download_minidump_stackwalk': True, 13:30:41 INFO - 'download_symbols': 'ondemand', 13:30:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:30:41 INFO - 'tooltool.py': '/tools/tooltool.py', 13:30:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:30:41 INFO - '/tools/misc-python/virtualenv.py')}, 13:30:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:30:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:30:41 INFO - 'gecko_profile': False, 13:30:41 INFO - 'gecko_profile_interval': 0, 13:30:41 INFO - 'installer_path': 'installer.exe', 13:30:41 INFO - 'log_level': 'info', 13:30:41 INFO - 'log_name': 'talos', 13:30:41 INFO - 'log_to_console': True, 13:30:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:30:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:30:41 INFO - 'opt_config_files': (), 13:30:41 INFO - 'pip_index': False, 13:30:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:41 INFO - 'cmd': ('bash', 13:30:41 INFO - '-c', 13:30:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:41 INFO - 'enabled': True, 13:30:41 INFO - 'halt_on_failure': False, 13:30:41 INFO - 'name': 'check_screen_resolution'},), 13:30:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:41 INFO - 'cmd': ('bash', 13:30:41 INFO - '-c', 13:30:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:41 INFO - 'enabled': True, 13:30:41 INFO - 'halt_on_failure': False, 13:30:41 INFO - 'name': 'check_screen_resolution'},), 13:30:41 INFO - 'run_cmd_checks_enabled': True, 13:30:41 INFO - 'suite': 'dromaeojs-e10s', 13:30:41 INFO - 'system_bits': '32', 13:30:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:30:41 INFO - 'title': 't-yosemite-r7-0303', 13:30:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:30:41 INFO - 'use_talos_json': True, 13:30:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:30:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:30:41 INFO - 'work_dir': 'build'} 13:30:41 INFO - [mozharness: 2017-05-19 20:30:41.541308Z] Running clobber step. 13:30:41 INFO - Running pre-action listener: _resource_record_pre_action 13:30:41 INFO - Running main action method: clobber 13:30:41 INFO - rmtree: /builds/slave/test/build 13:30:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:30:43 INFO - Running post-action listener: _resource_record_post_action 13:30:43 INFO - [mozharness: 2017-05-19 20:30:43.899380Z] Finished clobber step (success) 13:30:43 INFO - [mozharness: 2017-05-19 20:30:43.899457Z] Running read-buildbot-config step. 13:30:43 INFO - Running pre-action listener: _resource_record_pre_action 13:30:43 INFO - Running main action method: read_buildbot_config 13:30:43 INFO - Using buildbot properties: 13:30:43 INFO - { 13:30:43 INFO - "project": "", 13:30:43 INFO - "product": "firefox", 13:30:43 INFO - "script_repo_revision": "production", 13:30:43 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 13:30:43 INFO - "repository": "", 13:30:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 13:30:43 INFO - "buildid": "20170519115822", 13:30:43 INFO - "pgo_build": "False", 13:30:43 INFO - "basedir": "/builds/slave/test", 13:30:43 INFO - "buildnumber": 49, 13:30:43 INFO - "slavename": "t-yosemite-r7-0303", 13:30:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:30:43 INFO - "platform": "yosemite_r7", 13:30:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:30:43 INFO - "branch": "mozilla-beta", 13:30:43 INFO - "repo_path": "releases/mozilla-beta", 13:30:43 INFO - "revision": "0241994e3aad3594cae0d42c7d80a2dff7b245e8", 13:30:43 INFO - "stage_platform": "macosx64", 13:30:43 INFO - "builduid": "0fb720275bd54dc9a76655a97ef3555b", 13:30:43 INFO - "slavebuilddir": "test" 13:30:43 INFO - } 13:30:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 13:30:43 INFO - Running post-action listener: _resource_record_post_action 13:30:43 INFO - [mozharness: 2017-05-19 20:30:43.902801Z] Finished read-buildbot-config step (success) 13:30:43 INFO - [mozharness: 2017-05-19 20:30:43.902900Z] Running download-and-extract step. 13:30:43 INFO - Running pre-action listener: _resource_record_pre_action 13:30:43 INFO - Running main action method: download_and_extract 13:30:43 INFO - mkdir: /builds/slave/test/build/tests 13:30: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')]} 13:30:43 INFO - trying https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 13:30:43 INFO - Downloading https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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 13:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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 13:30:45 INFO - Downloaded 1287 bytes. 13:30:45 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:30:45 INFO - Using the following test package requirements: 13:30:45 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 13:30:45 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:30:45 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 13:30:45 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 13:30:45 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:30:45 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 13:30:45 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:30:45 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 13:30:45 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 13:30:45 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 13:30:45 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:30:45 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 13:30:45 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 13:30:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:30:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:30:45 INFO - Fetch https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:30:46 INFO - Expected file size: 51397337 13:30:46 INFO - Obtained file size: 51397337 13:31:02 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 13:31:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:31:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:31:02 INFO - Fetch https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:31:04 INFO - Expected file size: 51397337 13:31:04 INFO - Obtained file size: 51397337 13:31:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 13:31:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 13:31:19 INFO - Fetch https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 13:31:20 INFO - Expected file size: 14404449 13:31:20 INFO - Obtained file size: 14404449 13:31: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')]} 13:31:21 INFO - trying https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:31:21 INFO - Downloading https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 13:31:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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 13:31:22 INFO - Downloaded 59541826 bytes. 13:31:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:31:22 INFO - mkdir: /builds/slave/test/properties 13:31:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:31:22 INFO - Writing to file /builds/slave/test/properties/build_url 13:31:22 INFO - Contents: 13:31:22 INFO - build_url:https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:31:23 INFO - Running post-action listener: _resource_record_post_action 13:31:23 INFO - Running post-action listener: set_extra_try_arguments 13:31:23 INFO - [mozharness: 2017-05-19 20:31:23.541820Z] Finished download-and-extract step (success) 13:31:23 INFO - [mozharness: 2017-05-19 20:31:23.541988Z] Running populate-webroot step. 13:31:23 INFO - Running pre-action listener: _resource_record_pre_action 13:31:23 INFO - Running main action method: populate_webroot 13:31:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:31:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:31:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:31:23 INFO - '--tppagecycles', 13:31:23 INFO - '7'], 13:31:23 INFO - 'tests': ['tsvgm']}}, 13:31:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:31:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:31:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:31:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:31:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['damp', 'tps']}, 13:31:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'tests': ['damp', 'tps']}, 13:31:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['dromaeo_dom']}, 13:31:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:31:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['basic_compositor_video']}, 13:31:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:31:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['a11yr', 13:31:23 INFO - 'ts_paint', 13:31:23 INFO - 'tpaint', 13:31:23 INFO - 'sessionrestore', 13:31:23 INFO - 'sessionrestore_no_auto_restore', 13:31:23 INFO - 'tabpaint']}, 13:31:23 INFO - 'other-e10s': {'tests': ['a11yr', 13:31:23 INFO - 'ts_paint', 13:31:23 INFO - 'tpaint', 13:31:23 INFO - 'sessionrestore', 13:31:23 INFO - 'sessionrestore_no_auto_restore', 13:31:23 INFO - 'tabpaint']}, 13:31:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:31:23 INFO - 'ts_paint', 13:31:23 INFO - 'tpaint', 13:31:23 INFO - 'sessionrestore', 13:31:23 INFO - 'sessionrestore_no_auto_restore', 13:31:23 INFO - 'tabpaint']}, 13:31:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:31:23 INFO - 'ts_paint', 13:31:23 INFO - 'tpaint', 13:31:23 INFO - 'sessionrestore', 13:31:23 INFO - 'sessionrestore_no_auto_restore', 13:31:23 INFO - 'tabpaint']}, 13:31:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['a11yr', 13:31:23 INFO - 'ts_paint', 13:31:23 INFO - 'tpaint', 13:31:23 INFO - 'sessionrestore', 13:31:23 INFO - 'sessionrestore_no_auto_restore', 13:31:23 INFO - 'tabpaint']}, 13:31:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['a11yr', 13:31:23 INFO - 'ts_paint', 13:31:23 INFO - 'tpaint', 13:31:23 INFO - 'sessionrestore', 13:31:23 INFO - 'sessionrestore_no_auto_restore', 13:31:23 INFO - 'tabpaint']}, 13:31:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['tsvgx', 13:31:23 INFO - 'tsvgr_opacity', 13:31:23 INFO - 'tart', 13:31:23 INFO - 'tscrollx', 13:31:23 INFO - 'cart', 13:31:23 INFO - 'tsvg_static']}, 13:31:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:31:23 INFO - 'tsvgr_opacity', 13:31:23 INFO - 'tart', 13:31:23 INFO - 'tscrollx', 13:31:23 INFO - 'cart', 13:31:23 INFO - 'tsvg_static']}, 13:31:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'talos_options': ['--disable-e10s'], 13:31:23 INFO - 'tests': ['tp5o']}, 13:31:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:31:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'talos_options': ['--disable-e10s', 13:31:23 INFO - '--xperf_path', 13:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:23 INFO - 'tests': ['tp5n']}, 13:31:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:31:23 INFO - 'talos_options': ['--xperf_path', 13:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:23 INFO - 'tests': ['tp5n']}}, 13:31:23 INFO - 'talos.zip': {'path': '', 13:31:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:31:23 INFO - Running post-action listener: _resource_record_post_action 13:31:23 INFO - [mozharness: 2017-05-19 20:31:23.560903Z] Finished populate-webroot step (success) 13:31:23 INFO - [mozharness: 2017-05-19 20:31:23.561040Z] Running create-virtualenv step. 13:31:23 INFO - Running pre-action listener: _resource_record_pre_action 13:31:23 INFO - Running main action method: create_virtualenv 13:31:23 INFO - Creating virtualenv /builds/slave/test/build/venv 13:31:23 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 13:31:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:31:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:31:23 INFO - Using real prefix '/tools/python27' 13:31:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:31:24 INFO - Installing distribute.............................................................................................................................................................................................done. 13:31:27 INFO - Installing pip.................done. 13:31:27 INFO - Return code: 0 13:31:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:31:27 INFO - Reading from file tmpfile_stdout 13:31:27 INFO - Output received: 13:31:27 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) 13:31:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:31:27 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')]} 13:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:27 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')]} 13:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:27 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:27 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 13:31:27 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 13:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:27 INFO - 'HOME': '/Users/cltbld', 13:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:27 INFO - 'LOGNAME': 'cltbld', 13:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:27 INFO - 'MOZ_NO_REMOTE': '1', 13:31:27 INFO - 'NO_EM_RESTART': '1', 13:31:27 INFO - 'PAGER': '/bin/cat', 13:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:27 INFO - 'PWD': '/builds/slave/test', 13:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:27 INFO - 'SHELL': '/bin/bash', 13:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:27 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:27 INFO - 'USER': 'cltbld', 13:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:27 INFO - 'XPC_FLAGS': '0x0', 13:31:27 INFO - 'XPC_SERVICE_NAME': '0', 13:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:27 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 13:31:27 INFO - Cleaning up... 13:31:27 INFO - Return code: 0 13:31:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:31:27 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')]} 13:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:27 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')]} 13:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:27 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:27 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 13:31:27 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 13:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:27 INFO - 'HOME': '/Users/cltbld', 13:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:27 INFO - 'LOGNAME': 'cltbld', 13:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:27 INFO - 'MOZ_NO_REMOTE': '1', 13:31:27 INFO - 'NO_EM_RESTART': '1', 13:31:27 INFO - 'PAGER': '/bin/cat', 13:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:27 INFO - 'PWD': '/builds/slave/test', 13:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:27 INFO - 'SHELL': '/bin/bash', 13:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:27 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:27 INFO - 'USER': 'cltbld', 13:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:27 INFO - 'XPC_FLAGS': '0x0', 13:31:27 INFO - 'XPC_SERVICE_NAME': '0', 13:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:28 INFO - Downloading/unpacking psutil>=3.1.1 13:31:28 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 13:31:28 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 13:31:28 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 13:31:28 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 13:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:31:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:30 INFO - Installing collected packages: psutil 13:31:30 INFO - Running setup.py install for psutil 13:31:30 INFO - building 'psutil._psutil_osx' extension 13:31:30 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 13:31:30 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 13:31:30 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 13:31:30 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 13:31:30 INFO - building 'psutil._psutil_posix' extension 13:31:30 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 13:31:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:31:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:31:30 INFO - ^ 13:31:30 INFO - 1 warning generated. 13:31:30 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 13:31:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:31 INFO - Successfully installed psutil 13:31:31 INFO - Cleaning up... 13:31:31 INFO - Return code: 0 13:31:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:31 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:31 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 13:31:31 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 13:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:31 INFO - 'HOME': '/Users/cltbld', 13:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:31 INFO - 'LOGNAME': 'cltbld', 13:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:31 INFO - 'MOZ_NO_REMOTE': '1', 13:31:31 INFO - 'NO_EM_RESTART': '1', 13:31:31 INFO - 'PAGER': '/bin/cat', 13:31:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:31 INFO - 'PWD': '/builds/slave/test', 13:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:31 INFO - 'SHELL': '/bin/bash', 13:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:31 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:31 INFO - 'USER': 'cltbld', 13:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:31 INFO - 'XPC_FLAGS': '0x0', 13:31:31 INFO - 'XPC_SERVICE_NAME': '0', 13:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:31:31 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 13:31:31 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 13:31:31 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 13:31:31 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 13:31:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:31:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:31:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:31:32 INFO - Installing collected packages: mozsystemmonitor 13:31:32 INFO - Running setup.py install for mozsystemmonitor 13:31:33 INFO - Successfully installed mozsystemmonitor 13:31:33 INFO - Cleaning up... 13:31:33 INFO - Return code: 0 13:31:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:31:33 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')]} 13:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:33 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')]} 13:31:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:33 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:33 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 13:31:33 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 13:31:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:33 INFO - 'HOME': '/Users/cltbld', 13:31:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:33 INFO - 'LOGNAME': 'cltbld', 13:31:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:33 INFO - 'MOZ_NO_REMOTE': '1', 13:31:33 INFO - 'NO_EM_RESTART': '1', 13:31:33 INFO - 'PAGER': '/bin/cat', 13:31:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:33 INFO - 'PWD': '/builds/slave/test', 13:31:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:33 INFO - 'SHELL': '/bin/bash', 13:31:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:33 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:33 INFO - 'USER': 'cltbld', 13:31:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:33 INFO - 'XPC_FLAGS': '0x0', 13:31:33 INFO - 'XPC_SERVICE_NAME': '0', 13:31:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:33 INFO - Downloading/unpacking jsonschema==2.5.1 13:31:33 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 13:31:33 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 13:31:33 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 13:31:33 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 13:31:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:31:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:34 INFO - Downloading functools32-3.2.3-2.tar.gz 13:31:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:31:35 INFO - warning: no files found matching '*.txt' 13:31:35 INFO - no previously-included directories found matching 'build' 13:31:35 INFO - no previously-included directories found matching 'dist' 13:31:35 INFO - no previously-included directories found matching '.git*' 13:31:35 INFO - Installing collected packages: jsonschema, functools32 13:31:35 INFO - Running setup.py install for functools32 13:31:35 INFO - warning: no files found matching '*.txt' 13:31:35 INFO - no previously-included directories found matching 'build' 13:31:35 INFO - no previously-included directories found matching 'dist' 13:31:35 INFO - no previously-included directories found matching '.git*' 13:31:35 INFO - Successfully installed jsonschema functools32 13:31:35 INFO - Cleaning up... 13:31:35 INFO - Return code: 0 13:31:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:36 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:36 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 13:31:36 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 13:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:36 INFO - 'HOME': '/Users/cltbld', 13:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:36 INFO - 'LOGNAME': 'cltbld', 13:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:36 INFO - 'MOZ_NO_REMOTE': '1', 13:31:36 INFO - 'NO_EM_RESTART': '1', 13:31:36 INFO - 'PAGER': '/bin/cat', 13:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:36 INFO - 'PWD': '/builds/slave/test', 13:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:36 INFO - 'SHELL': '/bin/bash', 13:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:36 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:36 INFO - 'USER': 'cltbld', 13:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:36 INFO - 'XPC_FLAGS': '0x0', 13:31:36 INFO - 'XPC_SERVICE_NAME': '0', 13:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:31:36 INFO - Cleaning up... 13:31:36 INFO - Return code: 0 13:31:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:31:36 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')]} 13:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:36 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')]} 13:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:36 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:36 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 13:31:36 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 13:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:36 INFO - 'HOME': '/Users/cltbld', 13:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:36 INFO - 'LOGNAME': 'cltbld', 13:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:36 INFO - 'MOZ_NO_REMOTE': '1', 13:31:36 INFO - 'NO_EM_RESTART': '1', 13:31:36 INFO - 'PAGER': '/bin/cat', 13:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:36 INFO - 'PWD': '/builds/slave/test', 13:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:36 INFO - 'SHELL': '/bin/bash', 13:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:36 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:36 INFO - 'USER': 'cltbld', 13:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:36 INFO - 'XPC_FLAGS': '0x0', 13:31:36 INFO - 'XPC_SERVICE_NAME': '0', 13:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:36 INFO - Downloading/unpacking blobuploader==1.2.4 13:31:36 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 13:31:36 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 13:31:36 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 13:31:36 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 13:31:38 INFO - Downloading blobuploader-1.2.4.tar.gz 13:31:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:31:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:31:38 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 13:31:38 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 13:31:38 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 13:31:38 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 13:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:31:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 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 13:31:39 INFO - Downloading docopt-0.6.1.tar.gz 13:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:31:39 INFO - Installing collected packages: blobuploader, requests, docopt 13:31:39 INFO - Running setup.py install for blobuploader 13:31:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:31:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:31:39 INFO - Running setup.py install for requests 13:31:40 INFO - Running setup.py install for docopt 13:31:40 INFO - Successfully installed blobuploader requests docopt 13:31:40 INFO - Cleaning up... 13:31:40 INFO - Return code: 0 13:31:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:40 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')]} 13:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:40 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')]} 13:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:40 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:40 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 13:31:40 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 13:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:40 INFO - 'HOME': '/Users/cltbld', 13:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:40 INFO - 'LOGNAME': 'cltbld', 13:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:40 INFO - 'MOZ_NO_REMOTE': '1', 13:31:40 INFO - 'NO_EM_RESTART': '1', 13:31:40 INFO - 'PAGER': '/bin/cat', 13:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:40 INFO - 'PWD': '/builds/slave/test', 13:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:40 INFO - 'SHELL': '/bin/bash', 13:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:40 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:40 INFO - 'USER': 'cltbld', 13:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:40 INFO - 'XPC_FLAGS': '0x0', 13:31:40 INFO - 'XPC_SERVICE_NAME': '0', 13:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:40 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-cbhN9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:40 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-VBpRw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:40 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-daY2Eh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-iJg89S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-ZJkhey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-VknXlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-eCsNMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-10_sFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-FlBBto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-A9k2_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-rB6j5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:41 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-xLJGZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:42 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-k7Nffr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:42 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-9Rdr5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:42 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-mdPyrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:42 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-JZkxFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:42 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-qpU35y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:31:42 INFO - Running setup.py install for manifestparser 13:31:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:31:42 INFO - Running setup.py install for mozcrash 13:31:42 INFO - Running setup.py install for mozdebug 13:31:42 INFO - Running setup.py install for mozdevice 13:31:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozfile 13:31:43 INFO - Running setup.py install for mozhttpd 13:31:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozinfo 13:31:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozInstall 13:31:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:31:43 INFO - Running setup.py install for mozleak 13:31:43 INFO - Running setup.py install for mozlog 13:31:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for moznetwork 13:31:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozprocess 13:31:44 INFO - Running setup.py install for mozprofile 13:31:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozrunner 13:31:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:31:44 INFO - Running setup.py install for mozscreenshot 13:31:44 INFO - Running setup.py install for moztest 13:31:44 INFO - Running setup.py install for mozversion 13:31:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:31:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:31:45 INFO - Cleaning up... 13:31:45 INFO - Return code: 0 13:31:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:45 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:45 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 13:31:45 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 13:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:45 INFO - 'HOME': '/Users/cltbld', 13:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:45 INFO - 'LOGNAME': 'cltbld', 13:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:45 INFO - 'MOZ_NO_REMOTE': '1', 13:31:45 INFO - 'NO_EM_RESTART': '1', 13:31:45 INFO - 'PAGER': '/bin/cat', 13:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:45 INFO - 'PWD': '/builds/slave/test', 13:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:45 INFO - 'SHELL': '/bin/bash', 13:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:45 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:45 INFO - 'USER': 'cltbld', 13:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:45 INFO - 'XPC_FLAGS': '0x0', 13:31:45 INFO - 'XPC_SERVICE_NAME': '0', 13:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-yu0p4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-KHt7fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-7oOQga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-lMMsIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-wEA17h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:45 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)) 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:45 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-Fcq5Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-0c29ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-LXx0xt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-LXP_u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-ygTWUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-m9nMs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-J2fzc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-wmMhNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-enXHDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:46 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)) 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:46 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-JpIpFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:47 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)) 13:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:47 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-MPwDz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:47 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)) 13:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:47 INFO - Running setup.py (path:/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/pip-jlWpff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:47 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)) 13:31:47 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)) 13:31:47 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)) 13:31:47 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)) 13:31:47 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)) 13:31:47 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)) 13:31:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31: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 13:31: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 13:31: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 13:31: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 13:31:48 INFO - Downloading blessings-1.6.tar.gz 13:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:31:48 INFO - Installing collected packages: blessings 13:31:48 INFO - Running setup.py install for blessings 13:31:48 INFO - Successfully installed blessings 13:31:48 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:49 INFO - Reading from file tmpfile_stdout 13:31:49 INFO - Current package versions: 13:31:49 INFO - blessings == 1.6 13:31:49 INFO - blobuploader == 1.2.4 13:31:49 INFO - docopt == 0.6.1 13:31:49 INFO - functools32 == 3.2.3-2 13:31:49 INFO - jsonschema == 2.5.1 13:31:49 INFO - manifestparser == 1.1 13:31:49 INFO - mozInstall == 1.12 13:31:49 INFO - mozcrash == 1.0 13:31:49 INFO - mozdebug == 0.1 13:31:49 INFO - mozdevice == 0.49 13:31:49 INFO - mozfile == 1.2 13:31:49 INFO - mozhttpd == 0.7 13:31:49 INFO - mozinfo == 0.9 13:31:49 INFO - mozleak == 0.1 13:31:49 INFO - mozlog == 3.4 13:31:49 INFO - moznetwork == 0.27 13:31:49 INFO - mozprocess == 0.25 13:31:49 INFO - mozprofile == 0.28 13:31:49 INFO - mozrunner == 6.13 13:31:49 INFO - mozscreenshot == 0.1 13:31:49 INFO - mozsystemmonitor == 0.3 13:31:49 INFO - moztest == 0.8 13:31:49 INFO - mozversion == 1.4 13:31:49 INFO - psutil == 3.1.1 13:31:49 INFO - requests == 1.2.3 13:31:49 INFO - wsgiref == 0.1.2 13:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31: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')]} 13:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:49 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 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31:49 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 13:31:49 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 13:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:49 INFO - 'HOME': '/Users/cltbld', 13:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:49 INFO - 'LOGNAME': 'cltbld', 13:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:49 INFO - 'MOZ_NO_REMOTE': '1', 13:31:49 INFO - 'NO_EM_RESTART': '1', 13:31:49 INFO - 'PAGER': '/bin/cat', 13:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:49 INFO - 'PWD': '/builds/slave/test', 13:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:49 INFO - 'SHELL': '/bin/bash', 13:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:49 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:49 INFO - 'USER': 'cltbld', 13:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:49 INFO - 'XPC_FLAGS': '0x0', 13:31:49 INFO - 'XPC_SERVICE_NAME': '0', 13:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 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)) 13:31:49 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31: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')]} 13:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102b66b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102b69768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb930d6e560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, '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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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 13:31: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'] in /builds/slave/test/build 13:31: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 13:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:49 INFO - 'HOME': '/Users/cltbld', 13:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:49 INFO - 'LOGNAME': 'cltbld', 13:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:49 INFO - 'MOZ_NO_REMOTE': '1', 13:31:49 INFO - 'NO_EM_RESTART': '1', 13:31:49 INFO - 'PAGER': '/bin/cat', 13:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:49 INFO - 'PWD': '/builds/slave/test', 13:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:49 INFO - 'SHELL': '/bin/bash', 13:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:31:49 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:49 INFO - 'USER': 'cltbld', 13:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:49 INFO - 'XPC_FLAGS': '0x0', 13:31:49 INFO - 'XPC_SERVICE_NAME': '0', 13:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:31:49 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Running post-action listener: _resource_record_post_action 13:31:49 INFO - Running post-action listener: _start_resource_monitoring 13:31:49 INFO - Starting resource monitoring. 13:31:49 INFO - [mozharness: 2017-05-19 20:31:49.889702Z] Finished create-virtualenv step (success) 13:31:49 INFO - [mozharness: 2017-05-19 20:31:49.890662Z] Running install step. 13:31:49 INFO - Running pre-action listener: _resource_record_pre_action 13:31:49 INFO - Running main action method: install 13:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:50 INFO - Reading from file tmpfile_stdout 13:31:50 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:50 INFO - Reading from file tmpfile_stdout 13:31:50 INFO - Output received: 13:31:50 INFO - Usage: mozinstall [options] installer 13:31:50 INFO - Options: 13:31:50 INFO - -h, --help show this help message and exit 13:31:50 INFO - -d DEST, --destination=DEST 13:31:50 INFO - Directory to install application into. [default: 13:31:50 INFO - "/builds/slave/test"] 13:31:50 INFO - --app=APP Application being installed. [default: firefox] 13:31:50 INFO - mkdir: /builds/slave/test/build/application 13:31:50 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'] 13:31:50 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 13:32:05 INFO - Reading from file tmpfile_stdout 13:32:05 INFO - Output received: 13:32:05 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:32:05 INFO - Running post-action listener: _resource_record_post_action 13:32:05 INFO - [mozharness: 2017-05-19 20:32:05.302987Z] Finished install step (success) 13:32:05 INFO - [mozharness: 2017-05-19 20:32:05.303151Z] Running run-tests step. 13:32:05 INFO - Running pre-action listener: _resource_record_pre_action 13:32:05 INFO - Running main action method: run_tests 13:32:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:05 INFO - 2017-05-19 13:32:05.317 screenresolution[1630:7365] starting screenresolution argv=screenresolution get 13:32:05 INFO - 2017-05-19 13:32:05.320 screenresolution[1630:7365] Display 0: 1600x1200x32@60 13:32:05 INFO - 2017-05-19 13:32:05.326 screenresolution[1631:7367] starting screenresolution argv=screenresolution list 13:32:05 INFO - Available Modes on Display 0 13:32:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:05 INFO - Intel Iris: 13:32:05 INFO - Chipset Model: Intel Iris 13:32:05 INFO - Type: GPU 13:32:05 INFO - Bus: Built-In 13:32:05 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:05 INFO - Vendor: Intel (0x8086) 13:32:05 INFO - Device ID: 0x0a2e 13:32:05 INFO - Revision ID: 0x0009 13:32:05 INFO - Displays: 13:32:05 INFO - Display: 13:32:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:05 INFO - Display Serial Number: 1600x1200 60 13:32:05 INFO - Main Display: Yes 13:32:05 INFO - Mirror: Off 13:32:05 INFO - Online: Yes 13:32:05 INFO - Rotation: Supported 13:32:05 INFO - Return code: 0 13:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:32:05 INFO - Python 2.7.3 13:32:05 INFO - Return code: 0 13:32:05 INFO - grabbing minidump binary from tooltool 13:32: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')]} 13:32:05 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 0x102944d30>, '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 0x10286bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102939be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:32:05 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 13:32:05 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 13:32:05 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 13:32:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:32:05 INFO - Return code: 0 13:32:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:32:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:32:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:32:05 INFO - ENV: RUST_BACKTRACE is now 1 13:32:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:32:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:32:05 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/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0303', '--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 13:32:05 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/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0303 --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 13:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QfVNHFPhwq/Render', 13:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:05 INFO - 'HOME': '/Users/cltbld', 13:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:05 INFO - 'LOGNAME': 'cltbld', 13:32:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:32:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:05 INFO - 'MOZ_NO_REMOTE': '1', 13:32:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:32:05 INFO - 'NO_EM_RESTART': '1', 13:32:05 INFO - 'PAGER': '/bin/cat', 13:32:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:05 INFO - 'PWD': '/builds/slave/test', 13:32:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:05 INFO - 'RUST_BACKTRACE': '1', 13:32:05 INFO - 'SHELL': '/bin/bash', 13:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OtxZ2RvEmh/Listeners', 13:32:05 INFO - 'TMPDIR': '/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/', 13:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:05 INFO - 'USER': 'cltbld', 13:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:05 INFO - 'XPC_FLAGS': '0x0', 13:32:05 INFO - 'XPC_SERVICE_NAME': '0', 13:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:05 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/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0303', '--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 13:32:05 INFO - mozversion application_buildid: 20170519115822 13:32:05 INFO - mozversion application_changeset: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 13:32:05 INFO - mozversion application_display_name: Firefox 13:32:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:32:05 INFO - mozversion application_name: Firefox 13:32:05 INFO - mozversion application_remotingname: firefox 13:32:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:32:05 INFO - mozversion application_vendor: Mozilla 13:32:05 INFO - mozversion application_version: 54.0 13:32:05 INFO - mozversion platform_buildid: 20170519115822 13:32:05 INFO - mozversion platform_changeset: 0241994e3aad3594cae0d42c7d80a2dff7b245e8 13:32:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:32:05 INFO - mozversion platform_version: 54.0 13:32:05 INFO - using testdate: 1495225925 13:32:05 INFO - actual date: 1495225925 13:32:05 INFO - starting webserver on 'localhost:49227' 13:32:05 INFO - SUITE-START | Running 2 tests 13:32:05 INFO - TEST-START | dromaeo_css 13:32:05 INFO - operating with platform_type : mac_ 13:32:05 INFO - Initialising browser for dromaeo_css test... 13:32:05 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmp0rNPSX/profile http://localhost:49227/getInfo.html) 13:32:08 INFO - PROCESS | 1641 | 2017-05-19 13:32:08.522 plugin-container[1644:7532] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 13:32:08 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:08 INFO - PROCESS | 1641 | 2017-05-19 13:32:08.523 plugin-container[1644:7532] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 13:32:08 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:08 INFO - PROCESS | 1641 | 2017-05-19 13:32:08.523 plugin-container[1644:7532] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:32:08 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 13:32:08 INFO - PROCESS | 1641 | colorDepth:24 13:32:08 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 13:32:08 INFO - PROCESS | 1641 | __metrics 13:32:08 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 13:32:09 INFO - TEST-INFO | 1641: exit 0 13:32:09 INFO - Browser initialized. 13:32:09 INFO - Running cycle 1/1 for dromaeo_css test... 13:32:09 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmp0rNPSX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:11 INFO - PROCESS | 1645 | 2017-05-19 13:32:11.701 plugin-container[1646:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 13:32:11 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:11 INFO - PROCESS | 1645 | 2017-05-19 13:32:11.701 plugin-container[1646:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8717, name = 'com.apple.CFPasteboardClient' 13:32:11 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:11 INFO - PROCESS | 1645 | 2017-05-19 13:32:11.701 plugin-container[1646:7666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:32:21 INFO - PROCESS | 1645 | RSS: Main: 174051328 13:32:21 INFO - PROCESS | 1645 | 13:35:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 13:35:26 INFO - PROCESS | 1645 | RSS: Main: 191148032 13:35:26 INFO - PROCESS | 1645 | 13:38:28 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 13:38:29 INFO - PROCESS | 1645 | RSS: Main: 201060352 13:38:29 INFO - PROCESS | 1645 | 13:41:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 13:41:31 INFO - PROCESS | 1645 | RSS: Main: 204017664 13:41:31 INFO - PROCESS | 1645 | 13:44:33 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 13:44:33 INFO - PROCESS | 1645 | RSS: Main: 210833408 13:44:33 INFO - PROCESS | 1645 | 13:47:36 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 13:47:37 INFO - PROCESS | 1645 | RSS: Main: 213245952 13:47:37 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 13:50:40 INFO - PROCESS | 1645 | RSS: Main: 211611648 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | __start_tp_report 13:50:40 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 13:50:40 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 13:50:40 INFO - PROCESS | 1645 | |i|pagename|runs| 13:50:40 INFO - PROCESS | 1645 | |0;dojo.html;3127.6090488688915;3643.7267204959635;3651.2879988402274;3656.4698118772762;3663.9816800916;5203.375594928609;6049;6177.814665560035;6253.030780229061;6264.21697287839;6204.193454850874;6219.533534984872;6233.0962010508565;6251.687415629223;6252.499800015999;5245.31810864589;5690.374058853523;5764.625299355528;5811.331696854873;5813.128030795372;5622.156676498537;5881.823545293648;5908.438698323674;5920.703964801764;5927.585069045147;5553.278073850393;5593.2449119368775;5672.914906276409;5903.291605007405;5917.497012753921;5768.451997060272;5787.536997040227;5860.179574859523;5919.348871624118;5938.7624495020145;5231.973840130775;5367.677939323653;5674.943250567524;5765.308163020464;5875.941240587582;6128.17269668598;6237.625742455467;6297.874042519124;6338.746450141989;6348.936510634927;5623.775048998035;6486.2216534016225;6496;6506.674666266667;6614.669266536702;5646.548276137899;5800.970995144998;6904.171499420051;6904.275051119661;6906.827329316757;5216.7913283468615;6262.436380725757;6301.77943771975;6345.80962571119;6353.5234857385885;6617.8014659560295;6685;6771.593704377753;6936.514443988873;7024.367806897404;6499.642519661369;6538.346165383424;6587.802365928934;6593.879040563116;6594.7032383542855;6752.797416077526;6791.3548212919695;6811;6816.761413350609;6844.931550684529;5637.23897273871;6435.549511534147;6663.233728121231;6666.133402657623;6686.13080299558;6454.548181627335;6476.708548115346;6489.7728579500435;6519.674016299166;6561.606303621798;5387.299651045339;5393;5402.108652072364;5411.621186516984;5462.4767025268475;4614.907701845944;4769.403824521934;4792.113459010095;4793.32893394925;4813.061453016629;6132.4778862607845;6174.073888916699;6196.287426945869;6285.465735412541;6318.968405157945;5895.732417530252;6712.261651218362;6759.729610815611;6806.659667016728;6810.455163586901;6280.403361680633;6283.560150789493;6286.748530058747;6295.087212354275;6316.905246421307;5796.536277097906;5828.300603927556;5934.436228558367;5948.613340132878;6004.699765011732;6837.760678376233;6865.965670171518;6885.724571017103;6907.6891539879825;6926.79219623392;6814.420774234246;6817.386435220854;6817.5568588041615;6823.590584564942;6841.350071743176;6890.414314783299;6946.409555187865;6954.895676564956;6995.965020174866;7047.541909775849;6312.5581209314905;6383.808485745435;6549.115869357766;6570.981497867838;6723.798286051427;6794.660266986532;6795.218549866829;6797.864042719217;6800.183977922681;6808.897866532105;6187.659678717714;6211.440970312694;6323.241211054703;6417.486601071996;6486.318936511787;6607.438367738796;6617.106690596708;6618.6690665467495;6620.602763834186;6623.834404139935;6110.694465276807;6213.751449942132;6299.527535434952;6338.11266422692;6453.354664533509;6535.607863528204;6625.734970601122;6630.336966303331;6632.801015969529;6634.303398143318;6413.71138298787;6429.196350456193;6434.163558737334;6438.356164383524;6449.645269510222 13:50:40 INFO - PROCESS | 1645 | |1;ext.html;788.7397158937551;826.1366871619159;855.085058986884;939.7838497145652;995.3132338686311;40353;41614.16771664565;42796.78601606996;42913.141737165235;43013;41431;41648.79175604118;41702.58297417025;41751.79124104375;41919;8222.424430289868;8411.747647570583;11387.487563059682;11612.825807612893;11841.940790296036;9150.359474836763;11449.484773185184;11523.020543253791;11564.363959982198;11574.884251157464;11525;11565.363904985223;11578.42107894602;11595.884041159607;11606.59376921808;11506.712332191679;11542.538298468051;11548.480318385693;11557.017653499463;11558.19092663514;11286.363477295747;11286.98417142461;11335.489902954387;11338.886611133948;11352.43237838113;35588.82205588981;35808.46287305666;35954.460683089776;35991.64008359909;36010;3374.9493757593655;3957.98021009896;3966.2067586482817;3999.280129576675;4015.377616469452;26365.868170659214;26497.867510662516;27383.726162738516;28038.299042523897;28127.718722812715;3213.293075523381;3259.054874086512;3267;3278.1476816028003;3314.0720598232533;360361.39638603805;362227;362693;363800;364445;37382.626173738456;45482.31776523355;45598;45741;45778;48139.75930120327;48283.517164828605;48329.75835120802;48381.75809120932;48386.79033024132;14604.415823366946;14899;14944;14987;15004.699906001819;12734.61796146117;12757.808632870514;12785.424655890507;12787.61637150887;12788.360581970865;12170.391480425944;12323.630291091282;12328.32194229326;12384.195027323196;12429.192102513489;5783.190353350535;5793.942060579425;5810;5819.970900145472;5835.328937172195;1138.9487473063732;1159.3333833046033;1161.6108603617713;1182.8994535464583;1235;27410.177694669193;28105.71894281031;28122.71877281242;28202.43595128127;28695.426091478053;969.7974511605559;1126.645106791358;1200.1838749650146;1291.9935400323127;1340.9530666426629;10893.509792059218;11309.377984210783;12608.747825043447;14008.649783755283;15451.227438628024;515901;518325.40837295575;520137;520242;520668;40809.387859181545;50849.49150508447;50998.470045898684;51265.48734512607;51296.74351628218;1461.174436443406;1492.1867582167745;1497.9176145312113;1510.8338082810883;1525.305985776469;23492.412689682213;24174.39093597228;26646.333841654116;28489.678119995104;29498.705012949595;43732.56267437412;43881.56118438902;43941;44016.55983440253;44051.11897762091;479810;483395;484258;485026;485080.1491985035;31141.37717245597;31868.6813131875;31935.840320799176;32162.678373215967;32183.51724724084;41638.791806040776;42055.7897210512;42759.144817104105;43030.35454468248;43493.78253108841;29345.413091737617;29742.851285743433;29920.850395747882;30306.393872121993;30867.69132308738;23851.761482384954;29630;29677.554836677034;30043.699563004964;30504.694953050184;35182.82408587941;35601;35880;35922.281554368245;36251.81874090613;29678;30391;30531.389372212874;30560.84719576388;30966;19292.903535482234;24119;24166.51666966686;24309.149179778622;24366.878165609058 13:50:40 INFO - PROCESS | 1645 | |2;jquery.html;424.9978750106249;436.44135506551606;442.15327647554926;449.60210213960636;449.9246800147647;21430.821304828227;21691.3492595222;22174.445638859037;22451.101955921786;22828.744419056944;22742.88628556859;23385.830708464593;23924.880375598143;23971.16100936463;24130.75869241307;6776.593404395752;6839.418649414793;7006.01915731798;7118.893216601755;7143.3570978611915;6991.89512157318;7026.4730145239055;7091.85816283674;7595.620218989056;7954.443188976774;6827.761028364008;8096.190380961915;8116.513009219436;8121.147279535652;8142.145074767153;7381.594012329319;7567.432442566775;7663.118741344733;8025;8111.3916456266015;7654.928310036599;7830.804229894241;7877.487963282369;7920.6039698014865;7931.801704957365;26783.598246026326;26862;27321;27351.179464616092;27698.030568930193;3008.969910300913;3078.3843231353594;3096.5664806927048;3111.2688518198206;3122.8906988255303;16175.595610109724;16911.661766764595;17040.82959170405;17094.91452542729;17405.738913916302;1797.9640407191914;2145.184829764695;2210.35899589119;2238.5187184755355;2247.8763667998255;132502;167477;174980.12509937497;175153.12423437802;178856.10571947187;20853.68719469209;21709.891450542647;21749.891250543646;21998.13015893667;22218.88890555537;33109.503357449656;33782.49326260108;34060.31879362449;34094.147646309044;34202.48696269558;10250.436226007492;10785.83821242682;10842.620508282173;11197.552097915992;11332.943335283271;6781.62701051437;8979.416337938013;9143.49710765901;9161.679341223025;9338.813223735624;10030;10063.295569310225;10123.493825308706;10132.746681333025;10177.18582513397;3383.983080084584;3794.29805485986;4058.228936502055;4188.308929026676;4199.160167966419;903.8192361527589;915.1763412928285;933.2160984772823;957.3537861943161;979.0503211884461;20649.690254646193;21868.45328866759;21887.78112218889;21961.23135690243;21986;957.482959202037;984.450273790885;986.1863962231188;988.6935050134481;994.5524513968743;10929;11452;11480.885191147981;11499;11535.826962595567;724879;728528;752407;762707;779811;138618;162738;168306.31693682907;171863;173580;1222.1139673736648;1256.616731896762;1277.1251692590606;1279.4242590834162;1280.295837289487;18744.343947961755;18926.3375781847;19136.138873750435;19166.520836979187;19225.423237302904;36534.8173259132;62351.06473402812;63963.04055439265;64912;65286.34713652803;91419.62870556815;92479;92618.53690731504;93027.13945721183;94271.52864235635;11571.6528504145;11735.23720958135;11848.76302473963;11873.406329683308;12094.334811585448;11929.701757456132;13444.798328025283;13640.454381825013;13741.381637826122;13854.584362469142;11489.827652585385;11628.18602697803;11695.941520292345;11837;11896.881031189578;9471.337006409485;10532.36805791655;10735.785284294427;10854.945725271324;11099.833502497631;9529.904700952902;10459.68620941373;10996.890031099907;11099.416896532597;11871.753465886302;10166.491675416348;11652.53389864396;11818.940905295418;11910.583129590423;11998.700032499257;9950.955149709176;10142.746431339276;11537.423128843691;11734.061275098142;11897.988670962719 13:50:40 INFO - PROCESS | 1645 | |3;mootools.html;1611.2346635348204;1612.8468145276124;1640.52424796809;1652.611636265477;1658.07976573002;9953.850692239605;10179;11381.715457113576;11773.70565735856;11997.040236781058;9928.808542974835;11149.275297105682;11532.827007594893;11557.01765349944;11617.593384231554;2414.7102347718296;2463.9137630182945;2469.074097213545;2470.1354525915876;2470.8270421070533;2415.3696255027876;2461.643061756053;2465.9876700616473;2466.34641819917;2472.530219258344;2421.3583400398907;2428.344347026302;2436.2569416328015;2444.168982545934;2457.1768457566736;2423.491066875958;2431.987840060806;2434.8782560871887;2437.878106094697;2445.730969593343;2368.0764501844296;2389.020501594338;2389.474315650554;2393.593089174827;2398.7241467231315;11615.535378584846;11835.171537992344;11854.170208085437;11856.881431185664;11860.644180674593;5845.88308233833;5960.970195148997;6012;6030.216071910668;6035.336113027563;3584.7490675652716;3672.5042119313816;3674.540682414698;3691.6492933171307;3721.813909304551;1259.4899065878335;1262.4634530324668;1299.2529295654942;1309.796981467874;1316.1050485669741;15906.602334941603;15923.840761592468;15963.84036159635;15972;15974.840251597452;4733.2900064989835;4940.333055037524;4985.601151907838;5000.374953130859;5013.799448022111;13522.729545409036;13547.25490098054;13558.457661693421;13567.525136620368;13567.660808479866;5967.6419414835245;6033.909491357633;6056.485198758067;6062.211912451353;6075.27096748393;5200.713960732196;5320.414754377015;5378.650387724785;5382.838514844561;5399.136138217867;4018.3972404139613;4121.505419349697;4158.607350096669;4203.096334288142;4235.23765722173;1124.6401151631562;1128.700894263021;1129.1023636209234;1135.920485566019;1138.1918837625378;842.5534466732641;925.5927391947522;939.9765005874908;955.8518429643416;998.8052329795765;6494.512911531654;6613.801585952429;6614.801555953329;6718.697658605374;6727.495437842083;1010.833212519926;1060.2207377577474;1077.4074259157433;1086.299336927693;1087.6519513755682;4282.336237883133;4292;4311.374850646702;4454.37638730578;4466.754328511963;14150.504732334524;14263.07290026145;14663.48677796272;14666.56000319992;14715;14268.143911365143;14279.500652431345;14596.197209153386;14654.194019328826;14680.486182983544;9975.501224938724;10764.730881728019;10854.620088296871;10873.401962892116;10901.509432075418;6573.5398522103;6649.135612370361;6690.966545167437;6728.394444500019;6776.119104516381;12476.001919846572;12551.87448125507;12850.807237891626;12874.935625321814;12884.48462061507;11586.420678966186;11683.00694440982;12896.871031289942;13024.283664398548;13042.934785326013;8685.174908383693;8938.41900276506;8967.417117887317;9027;9031.322650801085;10725.570977160825;10734.83897741519;10782.245242832927;10817.675469735921;10830.945845270724;9158.725238242863;9205.125513076247;9240.122188392092;9426.62293508252;9457.290703197425;8985.921689397313;8992.955035224782;9004.954975225082;9112.589933453142;9267.86098208514;8115.594220288844;8142.8778568322705;8199.836003280021;8224.013118425828;8229.382796290422;5934.762609495745;5948.821535353946;5951;6144;6161.599496032743;5293.814716484904;5964.343922168645;6139.416755408229;6167.013277875519;6273.843153921189 13:50:40 INFO - PROCESS | 1645 | |4;prototype.html;399.4587334162212;399.63832731378096;399.6622853688632;402.5411031424177;404.6297637661538;5146.485351464861;5274.597884364583;5502.614816962814;5528.742867991644;5609.887802243953;3987.740796848203;4697.765111744417;4987.301777751118;5070.909754402803;5362.8123015694455;3776.7545109567927;4725.558704595097;4737.265723812815;4761.976190119062;4820.566149046585;4275.289884046375;4439.934415740231;4570.268756998881;4793.592544633693;4823.927641085386;3933.033483258371;3933.5083114610675;4306.105313661994;4486.685931984763;4781.354517140194;3841.8847434577006;4138.6482149017365;4177.561356057616;4190.5809419058005;4447.777611119431;3753.7530036518488;3845.288621605012;4355.368471571636;4374.912501749947;4568.862934111982;4735.668503204777;5376.784928602851;5400.540954018913;5401;5602.971985140089;5412.69960266885;5435.945640543623;5448.863778405532;5544.279243698294;5569.303837020372;5860.326062502824;5918.845825064114;5932.584719069667;5951.434613711691;5969.522438204933;4837;4918.680285781413;5009.699418034893;5023.191651005635;5081.212412076134;5054.660514963501;5474.698891560963;5639.379668236491;6169.7532098716;6251.187345645082;4439.64482841372;4505.279155335131;4655.975685349218;5027.472115427828;5198.960207958424;4858.902821943541;5021.799128034911;5389.946100539023;5402.540784033401;5405.837824865261;5285.577153827685;5476.507114359647;5509.917351239735;5613.298337707786;5717.4854263115685;4631.814727410933;5738.368779434259;5766.481016708516;5812.767489300465;5880.823575292662;1552.099782126364;1555.949733929593;1696.5843368374826;1830.9725354119698;1835.8709393722763;1032.4063663393579;1130.5138790320077;1229.8770122987808;1241.6399244219076;1347.7129341478865;4979.526944940225;5465.508104270635;5479.342478902558;5516.393196748354;5591.7483713233005;5283.041496761825;5445.727713614304;5629.155626656035;5858.736356863951;6072.483838873657;4814.229723244334;5128.974355128201;5265.97206606203;5525.254090697785;5535.446455354432;2648.47030593882;2804.8176868503483;3029.409265193266;3122.282744490521;3158.25232048115;5586.6871285247935;5821.721035039242;6214.689265536705;6271.6550589718;6292.685365731695;5721.713914304351;5847.766089356463;5954.950633569128;6065.636061836304;6184.907226391608;5269.762860671279;5346.385165705916;5559.861003474945;6113.877722445526;6219.32078338855;4997.450280469218;5444.727763611724;5558.97220513911;5568.248286481436;6041.9395806041375;5402.919416116871;5762.4525670061275;5831.550748685647;6018.398160183947;6065.60573562717;5163.690178589297;5552.750126244409;5737.454941780691;6211.757648470414;6270.460463512285;3979.3633018716864;4185.718783263068;4256.233877902007;4523.647703194386;4555.362249285036;5817.283901249057;5995.910061348996;6033.728482218398;6071.7571297147615;6162.753489860355;4687.601553867959;5783.450572195635;5843.678597677169;5863.472287494146;5897.498712609477;4853.830115945925;5382.67703937765;5593;5796.565257605781;5837.5329973601165;4968.732973091838;5369.221462887781;5704.09091363416;5726.370099289158;5762.4525670061275;4501.07727915783;5451.37309209446;5574.777008919597;5705.28998725602;5822.272215973004;5137.537621614073;5590.972045139748;5664.830055098354;5734.283214598176;5850.64896106235 13:50:40 INFO - PROCESS | 1645 | |5;yui.html;618.3352895637191;766.0424469413233;802.4022103532869;873.4060838629731;889.6041261638571;14320.498782542612;18192.727109093375;18363.449096527092;18429.631407371846;18465.35371262006;15481.303341349638;16288.592785180377;16405.42581009665;16458.75311870323;16496.092714900675;878.7363790862747;888.5868071346822;1002.7894142230141;1026.209818439801;1026.5431882812152;919.9448033118001;1020.6019652335596;1033.168299518887;1034.7154532503546;1035.5909415780761;839.559231403512;1029.0943969307;1060.247224470623;1060.8249638809625;1067.0076828549445;1056.0548309263168;1060.5280650110703;1060.7560261139943;1060.7719340341864;1061.6761887624348;780.4692808889953;784.5802495664849;785.7799816051514;786.8308313712522;789.9091604465502;17738.556536086573;17801.7329740054;17935.820641793674;18284.268629254813;18509.444716658523;14806.629834254121;15072.472463463726;15073.623159421102;15154.696906061818;15184.240787960558;11343.205975581712;11943.9847612953;11954.462049207807;12158.51365945361;12305.93847030759;4499.932501012488;4553.271476563734;4559.270516717343;4681.110588988081;4826.420829500447;91605;99148.51277230847;109457;110142.59429622805;114290.42854785673;9675.854862177073;11336.206465547333;11403.144764142722;11577.479013444246;11972.760544789055;22469.662955055686;22922.541549168924;23109.422264443525;23136;23188.65217021746;8171.79570510742;8171.79570510742;8195.754127376189;8198.672053117809;8446.493210407272;4077.959220407817;4447.3329000650165;4660.883477913079;4696.178168820511;4771.928421073687;3552.4848896909803;3607.85568577254;3608.891733248007;3623.655752703489;3626.5648122225502;542.818155917772;624.685037995014;645.9192600924885;667.9198496180489;670.6881300195432;519.8778287102527;540.9350877894677;548.8490665067138;573.8938296415176;583.4981915552621;10393;10511.106555942677;10630.574777008987;10973.451327433597;10990.890091098987;620.4106099205683;653.6731634182909;653.7319698923419;674.4132604634;683.8905775076064;4733.763311834395;5135.6918584885025;5144.43411224765;5157.8452646420665;5171.767270472763;108796;110928;112920;112994.43502782434;114281;71025.64487177531;71732;94132.64668383208;94226;96621;68010.65994669995;85117.14882851092;86153.56923215344;87004.56497717471;88835;9871.50642467888;9922.454265015493;9934.503274836374;9949.403035817873;9949.552270148006;49533.50466495289;55687;56128.5967850807;56905.86188276294;57993.420065798804;70931;71841;72058;72903;73314;11299;11568.363739994045;11622;11674;11693.356865372487;12452.875471245534;12523.874761252271;12652.30412327294;13184.011199160293;13800.102993305403;11735.61218333839;12036.458359374024;12036.819447708116;12065.879341206475;12152.635420937386;11561.364124972872;11882.28706277626;11925.34410607425;11926.463309150935;12068.698282543006;11314.157019242322;11672.649820505398;11954.10344224204;12083.214591051905;12176.817347739614;8826.338024648048;8892.955535222283;9136.497492637704;9248;9449.669261576088;9442.48066356357;9715.902840971783;9886.110250077527;10041.945595712345;10060.849087263843 13:50:40 INFO - PROCESS | 1645 | __end_tp_report 13:50:40 INFO - PROCESS | 1645 | __start_cc_report 13:50:40 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,392 13:50:40 INFO - PROCESS | 1645 | __end_cc_report 13:50:40 INFO - PROCESS | 1645 | __startTimestamp1495227040519__endTimestamp 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | ------- Summary: start ------- 13:50:40 INFO - PROCESS | 1645 | Number of tests: 6 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:6185.56 Median:6323.24 stddev:697.92 (11.0%) stddev-sans-first:657.54 13:50:40 INFO - PROCESS | 1645 | Values: 3127.6 3643.7 3651.3 3656.5 3664.0 5203.4 6049.0 6177.8 6253.0 6264.2 6204.2 6219.5 6233.1 6251.7 6252.5 5245.3 5690.4 5764.6 5811.3 5813.1 5622.2 5881.8 5908.4 5920.7 5927.6 5553.3 5593.2 5672.9 5903.3 5917.5 5768.5 5787.5 5860.2 5919.3 5938.8 5232.0 5367.7 5674.9 5765.3 5875.9 6128.2 6237.6 6297.9 6338.7 6348.9 5623.8 6486.2 6496.0 6506.7 6614.7 5646.5 5801.0 6904.2 6904.3 6906.8 5216.8 6262.4 6301.8 6345.8 6353.5 6617.8 6685.0 6771.6 6936.5 7024.4 6499.6 6538.3 6587.8 6593.9 6594.7 6752.8 6791.4 6811.0 6816.8 6844.9 5637.2 6435.5 6663.2 6666.1 6686.1 6454.5 6476.7 6489.8 6519.7 6561.6 5387.3 5393.0 5402.1 5411.6 5462.5 4614.9 4769.4 4792.1 4793.3 4813.1 6132.5 6174.1 6196.3 6285.5 6319.0 5895.7 6712.3 6759.7 6806.7 6810.5 6280.4 6283.6 6286.7 6295.1 6316.9 5796.5 5828.3 5934.4 5948.6 6004.7 6837.8 6866.0 6885.7 6907.7 6926.8 6814.4 6817.4 6817.6 6823.6 6841.4 6890.4 6946.4 6954.9 6996.0 7047.5 6312.6 6383.8 6549.1 6571.0 6723.8 6794.7 6795.2 6797.9 6800.2 6808.9 6187.7 6211.4 6323.2 6417.5 6486.3 6607.4 6617.1 6618.7 6620.6 6623.8 6110.7 6213.8 6299.5 6338.1 6453.4 6535.6 6625.7 6630.3 6632.8 6634.3 6413.7 6429.2 6434.2 6438.4 6449.6 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:58363.08 Median:31053.69 stddev:122420.63 (394.2%) stddev-sans-first:122687.73 13:50:40 INFO - PROCESS | 1645 | Values: 788.7 826.1 855.1 939.8 995.3 40353.0 41614.2 42796.8 42913.1 43013.0 41431.0 41648.8 41702.6 41751.8 41919.0 8222.4 8411.7 11387.5 11612.8 11841.9 9150.4 11449.5 11523.0 11564.4 11574.9 11525.0 11565.4 11578.4 11595.9 11606.6 11506.7 11542.5 11548.5 11557.0 11558.2 11286.4 11287.0 11335.5 11338.9 11352.4 35588.8 35808.5 35954.5 35991.6 36010.0 3374.9 3958.0 3966.2 3999.3 4015.4 26365.9 26497.9 27383.7 28038.3 28127.7 3213.3 3259.1 3267.0 3278.1 3314.1 360361.4 362227.0 362693.0 363800.0 364445.0 37382.6 45482.3 45598.0 45741.0 45778.0 48139.8 48283.5 48329.8 48381.8 48386.8 14604.4 14899.0 14944.0 14987.0 15004.7 12734.6 12757.8 12785.4 12787.6 12788.4 12170.4 12323.6 12328.3 12384.2 12429.2 5783.2 5793.9 5810.0 5820.0 5835.3 1138.9 1159.3 1161.6 1182.9 1235.0 27410.2 28105.7 28122.7 28202.4 28695.4 969.8 1126.6 1200.2 1292.0 1341.0 10893.5 11309.4 12608.7 14008.6 15451.2 515901.0 518325.4 520137.0 520242.0 520668.0 40809.4 50849.5 50998.5 51265.5 51296.7 1461.2 1492.2 1497.9 1510.8 1525.3 23492.4 24174.4 26646.3 28489.7 29498.7 43732.6 43881.6 43941.0 44016.6 44051.1 479810.0 483395.0 484258.0 485026.0 485080.1 31141.4 31868.7 31935.8 32162.7 32183.5 41638.8 42055.8 42759.1 43030.4 43493.8 29345.4 29742.9 29920.9 30306.4 30867.7 23851.8 29630.0 29677.6 30043.7 30504.7 35182.8 35601.0 35880.0 35922.3 36251.8 29678.0 30391.0 30531.4 30560.8 30966.0 19292.9 24119.0 24166.5 24309.1 24366.9 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:44119.85 Median:12495.38 stddev:125653.97 (1005.6%) stddev-sans-first:125963.63 13:50:40 INFO - PROCESS | 1645 | Values: 425.0 436.4 442.2 449.6 449.9 21430.8 21691.3 22174.4 22451.1 22828.7 22742.9 23385.8 23924.9 23971.2 24130.8 6776.6 6839.4 7006.0 7118.9 7143.4 6991.9 7026.5 7091.9 7595.6 7954.4 6827.8 8096.2 8116.5 8121.1 8142.1 7381.6 7567.4 7663.1 8025.0 8111.4 7654.9 7830.8 7877.5 7920.6 7931.8 26783.6 26862.0 27321.0 27351.2 27698.0 3009.0 3078.4 3096.6 3111.3 3122.9 16175.6 16911.7 17040.8 17094.9 17405.7 1798.0 2145.2 2210.4 2238.5 2247.9 132502.0 167477.0 174980.1 175153.1 178856.1 20853.7 21709.9 21749.9 21998.1 22218.9 33109.5 33782.5 34060.3 34094.1 34202.5 10250.4 10785.8 10842.6 11197.6 11332.9 6781.6 8979.4 9143.5 9161.7 9338.8 10030.0 10063.3 10123.5 10132.7 10177.2 3384.0 3794.3 4058.2 4188.3 4199.2 903.8 915.2 933.2 957.4 979.1 20649.7 21868.5 21887.8 21961.2 21986.0 957.5 984.5 986.2 988.7 994.6 10929.0 11452.0 11480.9 11499.0 11535.8 724879.0 728528.0 752407.0 762707.0 779811.0 138618.0 162738.0 168306.3 171863.0 173580.0 1222.1 1256.6 1277.1 1279.4 1280.3 18744.3 18926.3 19136.1 19166.5 19225.4 36534.8 62351.1 63963.0 64912.0 65286.3 91419.6 92479.0 92618.5 93027.1 94271.5 11571.7 11735.2 11848.8 11873.4 12094.3 11929.7 13444.8 13640.5 13741.4 13854.6 11489.8 11628.2 11695.9 11837.0 11896.9 9471.3 10532.4 10735.8 10854.9 11099.8 9529.9 10459.7 10996.9 11099.4 11871.8 10166.5 11652.5 11818.9 11910.6 11998.7 9951.0 10142.7 11537.4 11734.1 11898.0 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:7017.26 Median:2464.95 stddev:4449.10 (180.5%) stddev-sans-first:4443.03 13:50:40 INFO - PROCESS | 1645 | Values: 1611.2 1612.8 1640.5 1652.6 1658.1 9953.9 10179.0 11381.7 11773.7 11997.0 9928.8 11149.3 11532.8 11557.0 11617.6 2414.7 2463.9 2469.1 2470.1 2470.8 2415.4 2461.6 2466.0 2466.3 2472.5 2421.4 2428.3 2436.3 2444.2 2457.2 2423.5 2432.0 2434.9 2437.9 2445.7 2368.1 2389.0 2389.5 2393.6 2398.7 11615.5 11835.2 11854.2 11856.9 11860.6 5845.9 5961.0 6012.0 6030.2 6035.3 3584.7 3672.5 3674.5 3691.6 3721.8 1259.5 1262.5 1299.3 1309.8 1316.1 15906.6 15923.8 15963.8 15972.0 15974.8 4733.3 4940.3 4985.6 5000.4 5013.8 13522.7 13547.3 13558.5 13567.5 13567.7 5967.6 6033.9 6056.5 6062.2 6075.3 5200.7 5320.4 5378.7 5382.8 5399.1 4018.4 4121.5 4158.6 4203.1 4235.2 1124.6 1128.7 1129.1 1135.9 1138.2 842.6 925.6 940.0 955.9 998.8 6494.5 6613.8 6614.8 6718.7 6727.5 1010.8 1060.2 1077.4 1086.3 1087.7 4282.3 4292.0 4311.4 4454.4 4466.8 14150.5 14263.1 14663.5 14666.6 14715.0 14268.1 14279.5 14596.2 14654.2 14680.5 9975.5 10764.7 10854.6 10873.4 10901.5 6573.5 6649.1 6691.0 6728.4 6776.1 12476.0 12551.9 12850.8 12874.9 12884.5 11586.4 11683.0 12896.9 13024.3 13042.9 8685.2 8938.4 8967.4 9027.0 9031.3 10725.6 10734.8 10782.2 10817.7 10830.9 9158.7 9205.1 9240.1 9426.6 9457.3 8985.9 8993.0 9005.0 9112.6 9267.9 8115.6 8142.9 8199.8 8224.0 8229.4 5934.8 5948.8 5951.0 6144.0 6161.6 5293.8 5964.3 6139.4 6167.0 6273.8 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4883.27 Median:5395.24 stddev:1355.08 (25.1%) stddev-sans-first:1316.42 13:50:40 INFO - PROCESS | 1645 | Values: 399.5 399.6 399.7 402.5 404.6 5146.5 5274.6 5502.6 5528.7 5609.9 3987.7 4697.8 4987.3 5070.9 5362.8 3776.8 4725.6 4737.3 4762.0 4820.6 4275.3 4439.9 4570.3 4793.6 4823.9 3933.0 3933.5 4306.1 4486.7 4781.4 3841.9 4138.6 4177.6 4190.6 4447.8 3753.8 3845.3 4355.4 4374.9 4568.9 4735.7 5376.8 5400.5 5401.0 5603.0 5412.7 5435.9 5448.9 5544.3 5569.3 5860.3 5918.8 5932.6 5951.4 5969.5 4837.0 4918.7 5009.7 5023.2 5081.2 5054.7 5474.7 5639.4 6169.8 6251.2 4439.6 4505.3 4656.0 5027.5 5199.0 4858.9 5021.8 5389.9 5402.5 5405.8 5285.6 5476.5 5509.9 5613.3 5717.5 4631.8 5738.4 5766.5 5812.8 5880.8 1552.1 1555.9 1696.6 1831.0 1835.9 1032.4 1130.5 1229.9 1241.6 1347.7 4979.5 5465.5 5479.3 5516.4 5591.7 5283.0 5445.7 5629.2 5858.7 6072.5 4814.2 5129.0 5266.0 5525.3 5535.4 2648.5 2804.8 3029.4 3122.3 3158.3 5586.7 5821.7 6214.7 6271.7 6292.7 5721.7 5847.8 5955.0 6065.6 6184.9 5269.8 5346.4 5559.9 6113.9 6219.3 4997.5 5444.7 5559.0 5568.2 6041.9 5402.9 5762.5 5831.6 6018.4 6065.6 5163.7 5552.8 5737.5 6211.8 6270.5 3979.4 4185.7 4256.2 4523.6 4555.4 5817.3 5995.9 6033.7 6071.8 6162.8 4687.6 5783.5 5843.7 5863.5 5897.5 4853.8 5382.7 5593.0 5796.6 5837.5 4968.7 5369.2 5704.1 5726.4 5762.5 4501.1 5451.4 5574.8 5705.3 5822.3 5137.5 5591.0 5664.8 5734.3 5850.6 13:50:40 INFO - PROCESS | 1645 | 13:50:40 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:21089.97 Median:3580.17 stddev:30584.93 (854.3%) stddev-sans-first:30632.10 13:50:40 INFO - PROCESS | 1645 | Values: 618.3 766.0 802.4 873.4 889.6 14320.5 18192.7 18363.4 18429.6 18465.4 15481.3 16288.6 16405.4 16458.8 16496.1 878.7 888.6 1002.8 1026.2 1026.5 919.9 1020.6 1033.2 1034.7 1035.6 839.6 1029.1 1060.2 1060.8 1067.0 1056.1 1060.5 1060.8 1060.8 1061.7 780.5 784.6 785.8 786.8 789.9 17738.6 17801.7 17935.8 18284.3 18509.4 14806.6 15072.5 15073.6 15154.7 15184.2 11343.2 11944.0 11954.5 12158.5 12305.9 4499.9 4553.3 4559.3 4681.1 4826.4 91605.0 99148.5 109457.0 110142.6 114290.4 9675.9 11336.2 11403.1 11577.5 11972.8 22469.7 22922.5 23109.4 23136.0 23188.7 8171.8 8171.8 8195.8 8198.7 8446.5 4078.0 4447.3 4660.9 4696.2 4771.9 3552.5 3607.9 3608.9 3623.7 3626.6 542.8 624.7 645.9 667.9 670.7 519.9 540.9 548.8 573.9 583.5 10393.0 10511.1 10630.6 10973.5 10990.9 620.4 653.7 653.7 674.4 683.9 4733.8 5135.7 5144.4 5157.8 5171.8 108796.0 110928.0 112920.0 112994.4 114281.0 71025.6 71732.0 94132.6 94226.0 96621.0 68010.7 85117.1 86153.6 87004.6 88835.0 9871.5 9922.5 9934.5 9949.4 9949.6 49533.5 55687.0 56128.6 56905.9 57993.4 70931.0 71841.0 72058.0 72903.0 73314.0 11299.0 11568.4 11622.0 11674.0 11693.4 12452.9 12523.9 12652.3 13184.0 13800.1 11735.6 12036.5 12036.8 12065.9 12152.6 11561.4 11882.3 11925.3 11926.5 12068.7 11314.2 11672.6 11954.1 12083.2 12176.8 8826.3 8893.0 9136.5 9248.0 9449.7 9442.5 9715.9 9886.1 10041.9 10060.8 13:50:40 INFO - PROCESS | 1645 | -------- Summary: end -------- 13:50:40 INFO - PROCESS | 1645 | 13:50:41 INFO - TEST-INFO | 1645: exit 0 13:50:41 INFO - TEST-OK | dromaeo_css | took 1115171ms 13:50:41 INFO - TEST-START | kraken 13:50:41 INFO - operating with platform_type : mac_ 13:50:41 INFO - Initialising browser for kraken test... 13:50:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpphjHYw/profile http://localhost:49227/getInfo.html) 13:50:43 INFO - PROCESS | 1657 | 2017-05-19 13:50:43.585 plugin-container[1659:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 13:50:43 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:43 INFO - PROCESS | 1657 | 2017-05-19 13:50:43.585 plugin-container[1659:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 13:50:43 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:43 INFO - PROCESS | 1657 | 2017-05-19 13:50:43.585 plugin-container[1659:10147] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:50:43 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 13:50:43 INFO - PROCESS | 1657 | colorDepth:24 13:50:43 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 13:50:43 INFO - PROCESS | 1657 | __metrics 13:50:43 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 13:50:44 INFO - TEST-INFO | 1657: exit 0 13:50:44 INFO - Browser initialized. 13:50:44 INFO - Running cycle 1/1 for kraken test... 13:50:44 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/T/tmpphjHYw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:50:46 INFO - PROCESS | 1660 | 2017-05-19 13:50:46.633 plugin-container[1661:10280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8547, name = 'com.apple.tsm.portname' 13:50:46 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:46 INFO - PROCESS | 1660 | 2017-05-19 13:50:46.634 plugin-container[1661:10280] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 13:50:46 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:46 INFO - PROCESS | 1660 | 2017-05-19 13:50:46.634 plugin-container[1661:10280] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:50:56 INFO - PROCESS | 1660 | RSS: Main: 174202880 13:50:56 INFO - PROCESS | 1660 | 13:50:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 13:51:00 INFO - PROCESS | 1660 | RSS: Main: 187924480 13:51:00 INFO - PROCESS | 1660 | 13:51:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 13:51:03 INFO - PROCESS | 1660 | RSS: Main: 195801088 13:51:03 INFO - PROCESS | 1660 | 13:51:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 13:51:07 INFO - PROCESS | 1660 | RSS: Main: 199397376 13:51:07 INFO - PROCESS | 1660 | 13:51:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 13:51:10 INFO - PROCESS | 1660 | RSS: Main: 199942144 13:51:10 INFO - PROCESS | 1660 | 13:51:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:51:12 INFO - PROCESS | 1660 | RSS: Main: 201351168 13:51:12 INFO - PROCESS | 1660 | 13:51:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 13:51:16 INFO - PROCESS | 1660 | RSS: Main: 205205504 13:51:16 INFO - PROCESS | 1660 | 13:51:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 13:51:19 INFO - PROCESS | 1660 | RSS: Main: 204836864 13:51:19 INFO - PROCESS | 1660 | 13:51:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 13:51:23 INFO - PROCESS | 1660 | RSS: Main: 204967936 13:51:23 INFO - PROCESS | 1660 | 13:51:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:51:25 INFO - PROCESS | 1660 | RSS: Main: 204431360 13:51:25 INFO - PROCESS | 1660 | 13:51:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:51:28 INFO - PROCESS | 1660 | RSS: Main: 204419072 13:51:28 INFO - PROCESS | 1660 | 13:51:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:51:31 INFO - PROCESS | 1660 | RSS: Main: 204431360 13:51:31 INFO - PROCESS | 1660 | 13:51:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:51:34 INFO - PROCESS | 1660 | RSS: Main: 204869632 13:51:34 INFO - PROCESS | 1660 | 13:51:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:51:38 INFO - PROCESS | 1660 | RSS: Main: 204955648 13:51:38 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 13:51:40 INFO - PROCESS | 1660 | RSS: Main: 207085568 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | __start_tp_report 13:51:40 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:51:40 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:51:40 INFO - PROCESS | 1660 | |i|pagename|runs| 13:51:40 INFO - PROCESS | 1660 | |0;ai-astar;106;109;170;174;168;122;118;144;112;144 13:51:40 INFO - PROCESS | 1660 | |1;audio-beat-detection;141;103;97;164;155;136;112;110;139;139 13:51:40 INFO - PROCESS | 1660 | |2;audio-dft;202;192;235;272;206;197;198;195;195;215 13:51:40 INFO - PROCESS | 1660 | |3;audio-fft;81;57;57;86;60;61;62;58;72;60 13:51:40 INFO - PROCESS | 1660 | |4;audio-oscillator;83;70;83;70;71;71;81;69;70;79 13:51:40 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;97;133;131;88;88;101;90;88;89;89 13:51:40 INFO - PROCESS | 1660 | |6;imaging-darkroom;95;96;96;96;96;95;104;96;95;96 13:51:40 INFO - PROCESS | 1660 | |7;imaging-desaturate;143;135;91;94;108;91;96;103;91;93 13:51:40 INFO - PROCESS | 1660 | |8;json-parse-financial;65;65;64;68;68;69;67;68;69;68 13:51:40 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;59;66;70;98;90;61;59;57;59;61 13:51:40 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;84;83;83;75;99;96;97;86;82;82 13:51:40 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;136;144;139;140;140;134;146;162;190;174 13:51:40 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;162;161;181;219;199;159;160;163;163;164 13:51:40 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;66;59;65;64;65;63;64;65;64;63 13:51:40 INFO - PROCESS | 1660 | __end_tp_report 13:51:40 INFO - PROCESS | 1660 | __start_cc_report 13:51:40 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,983 13:51:40 INFO - PROCESS | 1660 | __end_cc_report 13:51:40 INFO - PROCESS | 1660 | __startTimestamp1495227100321__endTimestamp 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:51:40 INFO - PROCESS | 1660 | Number of tests: 14 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:136.70 Median:144.00 stddev:26.82 (18.6%) stddev-sans-first:26.04 13:51:40 INFO - PROCESS | 1660 | Values: 106.0 109.0 170.0 174.0 168.0 122.0 118.0 144.0 112.0 144.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:140.00 stddev:22.71 (16.2%) stddev-sans-first:23.71 13:51:40 INFO - PROCESS | 1660 | Values: 141.0 103.0 97.0 164.0 155.0 136.0 112.0 110.0 139.0 139.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:210.70 Median:204.00 stddev:25.07 (12.3%) stddev-sans-first:26.39 13:51:40 INFO - PROCESS | 1660 | Values: 202.0 192.0 235.0 272.0 206.0 197.0 198.0 195.0 195.0 215.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:65.40 Median:61.50 stddev:10.52 (17.1%) stddev-sans-first:9.53 13:51:40 INFO - PROCESS | 1660 | Values: 81.0 57.0 57.0 86.0 60.0 61.0 62.0 58.0 72.0 60.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:75.00 stddev:5.98 (8.0%) stddev-sans-first:5.54 13:51:40 INFO - PROCESS | 1660 | Values: 83.0 70.0 83.0 70.0 71.0 71.0 81.0 69.0 70.0 79.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:88.50 stddev:17.73 (20.0%) stddev-sans-first:18.79 13:51:40 INFO - PROCESS | 1660 | Values: 97.0 133.0 131.0 88.0 88.0 101.0 90.0 88.0 89.0 89.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:96.00 stddev:2.68 (2.8%) stddev-sans-first:2.78 13:51:40 INFO - PROCESS | 1660 | Values: 95.0 96.0 96.0 96.0 96.0 95.0 104.0 96.0 95.0 96.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:91.00 stddev:19.12 (21.0%) stddev-sans-first:14.32 13:51:40 INFO - PROCESS | 1660 | Values: 143.0 135.0 91.0 94.0 108.0 91.0 96.0 103.0 91.0 93.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:1.79 (2.6%) stddev-sans-first:1.73 13:51:40 INFO - PROCESS | 1660 | Values: 65.0 65.0 64.0 68.0 68.0 69.0 67.0 68.0 69.0 68.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:63.50 stddev:14.35 (22.6%) stddev-sans-first:14.85 13:51:40 INFO - PROCESS | 1660 | Values: 59.0 66.0 70.0 98.0 90.0 61.0 59.0 57.0 59.0 61.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:7.89 (9.3%) stddev-sans-first:8.31 13:51:40 INFO - PROCESS | 1660 | Values: 84.0 83.0 83.0 75.0 99.0 96.0 97.0 86.0 82.0 82.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:145.00 stddev:18.69 (12.9%) stddev-sans-first:19.07 13:51:40 INFO - PROCESS | 1660 | Values: 136.0 144.0 139.0 140.0 140.0 134.0 146.0 162.0 190.0 174.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:163.50 stddev:20.46 (12.5%) stddev-sans-first:21.30 13:51:40 INFO - PROCESS | 1660 | Values: 162.0 161.0 181.0 219.0 199.0 159.0 160.0 163.0 163.0 164.0 13:51:40 INFO - PROCESS | 1660 | 13:51:40 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.50 stddev:1.93 (3.0%) stddev-sans-first:1.88 13:51:40 INFO - PROCESS | 1660 | Values: 66.0 59.0 65.0 64.0 65.0 63.0 64.0 65.0 64.0 63.0 13:51:40 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:51:40 INFO - PROCESS | 1660 | 13:51:41 INFO - TEST-INFO | 1660: exit 0 13:51:41 INFO - TEST-OK | kraken | took 60033ms 13:51:41 INFO - SUITE-END | took 1175s 13:51:41 INFO - Completed test suite (00:19:35) 13:51:41 INFO - javascript benchmark 13:51:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7986.808285650494, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.6090488688915, 3643.7267204959635, 3651.2879988402274, 3656.4698118772762, 3663.9816800916, 5203.375594928609, 6049.0, 6177.814665560035, 6253.030780229061, 6264.21697287839, 6204.193454850874, 6219.533534984872, 6233.0962010508565, 6251.687415629223, 6252.499800015999, 5245.31810864589, 5690.374058853523, 5764.625299355528, 5811.331696854873, 5813.128030795372, 5622.156676498537, 5881.823545293648, 5908.438698323674, 5920.703964801764, 5927.585069045147, 5553.278073850393, 5593.2449119368775, 5672.914906276409, 5903.291605007405, 5917.497012753921, 5768.451997060272, 5787.536997040227, 5860.179574859523, 5919.348871624118, 5938.7624495020145, 5231.973840130775, 5367.677939323653, 5674.943250567524, 5765.308163020464, 5875.941240587582, 6128.17269668598, 6237.625742455467, 6297.874042519124, 6338.746450141989, 6348.936510634927, 5623.775048998035, 6486.2216534016225, 6496.0, 6506.674666266667, 6614.669266536702, 5646.548276137899, 5800.970995144998, 6904.171499420051, 6904.275051119661, 6906.827329316757, 5216.7913283468615, 6262.436380725757, 6301.77943771975, 6345.80962571119, 6353.5234857385885, 6617.8014659560295, 6685.0, 6771.593704377753, 6936.514443988873, 7024.367806897404, 6499.642519661369, 6538.346165383424, 6587.802365928934, 6593.879040563116, 6594.7032383542855, 6752.797416077526, 6791.3548212919695, 6811.0, 6816.761413350609, 6844.931550684529, 5637.23897273871, 6435.549511534147, 6663.233728121231, 6666.133402657623, 6686.13080299558, 6454.548181627335, 6476.708548115346, 6489.7728579500435, 6519.674016299166, 6561.606303621798, 5387.299651045339, 5393.0, 5402.108652072364, 5411.621186516984, 5462.4767025268475, 4614.907701845944, 4769.403824521934, 4792.113459010095, 4793.32893394925, 4813.061453016629, 6132.4778862607845, 6174.073888916699, 6196.287426945869, 6285.465735412541, 6318.968405157945, 5895.732417530252, 6712.261651218362, 6759.729610815611, 6806.659667016728, 6810.455163586901, 6280.403361680633, 6283.560150789493, 6286.748530058747, 6295.087212354275, 6316.905246421307, 5796.536277097906, 5828.300603927556, 5934.436228558367, 5948.613340132878, 6004.699765011732, 6837.760678376233, 6865.965670171518, 6885.724571017103, 6907.6891539879825, 6926.79219623392, 6814.420774234246, 6817.386435220854, 6817.5568588041615, 6823.590584564942, 6841.350071743176, 6890.414314783299, 6946.409555187865, 6954.895676564956, 6995.965020174866, 7047.541909775849, 6312.5581209314905, 6383.808485745435, 6549.115869357766, 6570.981497867838, 6723.798286051427, 6794.660266986532, 6795.218549866829, 6797.864042719217, 6800.183977922681, 6808.897866532105, 6187.659678717714, 6211.440970312694, 6323.241211054703, 6417.486601071996, 6486.318936511787, 6607.438367738796, 6617.106690596708, 6618.6690665467495, 6620.602763834186, 6623.834404139935, 6110.694465276807, 6213.751449942132, 6299.527535434952, 6338.11266422692, 6453.354664533509, 6535.607863528204, 6625.734970601122, 6630.336966303331, 6632.801015969529, 6634.303398143318, 6413.71138298787, 6429.196350456193, 6434.163558737334, 6438.356164383524, 6449.645269510222], "value": 6142.119932590563, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.7397158937551, 826.1366871619159, 855.085058986884, 939.7838497145652, 995.3132338686311, 40353.0, 41614.16771664565, 42796.78601606996, 42913.141737165235, 43013.0, 41431.0, 41648.79175604118, 41702.58297417025, 41751.79124104375, 41919.0, 8222.424430289868, 8411.747647570583, 11387.487563059682, 11612.825807612893, 11841.940790296036, 9150.359474836763, 11449.484773185184, 11523.020543253791, 11564.363959982198, 11574.884251157464, 11525.0, 11565.363904985223, 11578.42107894602, 11595.884041159607, 11606.59376921808, 11506.712332191679, 11542.538298468051, 11548.480318385693, 11557.017653499463, 11558.19092663514, 11286.363477295747, 11286.98417142461, 11335.489902954387, 11338.886611133948, 11352.43237838113, 35588.82205588981, 35808.46287305666, 35954.460683089776, 35991.64008359909, 36010.0, 3374.9493757593655, 3957.98021009896, 3966.2067586482817, 3999.280129576675, 4015.377616469452, 26365.868170659214, 26497.867510662516, 27383.726162738516, 28038.299042523897, 28127.718722812715, 3213.293075523381, 3259.054874086512, 3267.0, 3278.1476816028003, 3314.0720598232533, 360361.39638603805, 362227.0, 362693.0, 363800.0, 364445.0, 37382.626173738456, 45482.31776523355, 45598.0, 45741.0, 45778.0, 48139.75930120327, 48283.517164828605, 48329.75835120802, 48381.75809120932, 48386.79033024132, 14604.415823366946, 14899.0, 14944.0, 14987.0, 15004.699906001819, 12734.61796146117, 12757.808632870514, 12785.424655890507, 12787.61637150887, 12788.360581970865, 12170.391480425944, 12323.630291091282, 12328.32194229326, 12384.195027323196, 12429.192102513489, 5783.190353350535, 5793.942060579425, 5810.0, 5819.970900145472, 5835.328937172195, 1138.9487473063732, 1159.3333833046033, 1161.6108603617713, 1182.8994535464583, 1235.0, 27410.177694669193, 28105.71894281031, 28122.71877281242, 28202.43595128127, 28695.426091478053, 969.7974511605559, 1126.645106791358, 1200.1838749650146, 1291.9935400323127, 1340.9530666426629, 10893.509792059218, 11309.377984210783, 12608.747825043447, 14008.649783755283, 15451.227438628024, 515901.0, 518325.40837295575, 520137.0, 520242.0, 520668.0, 40809.387859181545, 50849.49150508447, 50998.470045898684, 51265.48734512607, 51296.74351628218, 1461.174436443406, 1492.1867582167745, 1497.9176145312113, 1510.8338082810883, 1525.305985776469, 23492.412689682213, 24174.39093597228, 26646.333841654116, 28489.678119995104, 29498.705012949595, 43732.56267437412, 43881.56118438902, 43941.0, 44016.55983440253, 44051.11897762091, 479810.0, 483395.0, 484258.0, 485026.0, 485080.1491985035, 31141.37717245597, 31868.6813131875, 31935.840320799176, 32162.678373215967, 32183.51724724084, 41638.791806040776, 42055.7897210512, 42759.144817104105, 43030.35454468248, 43493.78253108841, 29345.413091737617, 29742.851285743433, 29920.850395747882, 30306.393872121993, 30867.69132308738, 23851.761482384954, 29630.0, 29677.554836677034, 30043.699563004964, 30504.694953050184, 35182.82408587941, 35601.0, 35880.0, 35922.281554368245, 36251.81874090613, 29678.0, 30391.0, 30531.389372212874, 30560.84719576388, 30966.0, 19292.903535482234, 24119.0, 24166.51666966686, 24309.149179778622, 24366.878165609058], "value": 19011.344903328652, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.9978750106249, 436.44135506551606, 442.15327647554926, 449.60210213960636, 449.9246800147647, 21430.821304828227, 21691.3492595222, 22174.445638859037, 22451.101955921786, 22828.744419056944, 22742.88628556859, 23385.830708464593, 23924.880375598143, 23971.16100936463, 24130.75869241307, 6776.593404395752, 6839.418649414793, 7006.01915731798, 7118.893216601755, 7143.3570978611915, 6991.89512157318, 7026.4730145239055, 7091.85816283674, 7595.620218989056, 7954.443188976774, 6827.761028364008, 8096.190380961915, 8116.513009219436, 8121.147279535652, 8142.145074767153, 7381.594012329319, 7567.432442566775, 7663.118741344733, 8025.0, 8111.3916456266015, 7654.928310036599, 7830.804229894241, 7877.487963282369, 7920.6039698014865, 7931.801704957365, 26783.598246026326, 26862.0, 27321.0, 27351.179464616092, 27698.030568930193, 3008.969910300913, 3078.3843231353594, 3096.5664806927048, 3111.2688518198206, 3122.8906988255303, 16175.595610109724, 16911.661766764595, 17040.82959170405, 17094.91452542729, 17405.738913916302, 1797.9640407191914, 2145.184829764695, 2210.35899589119, 2238.5187184755355, 2247.8763667998255, 132502.0, 167477.0, 174980.12509937497, 175153.12423437802, 178856.10571947187, 20853.68719469209, 21709.891450542647, 21749.891250543646, 21998.13015893667, 22218.88890555537, 33109.503357449656, 33782.49326260108, 34060.31879362449, 34094.147646309044, 34202.48696269558, 10250.436226007492, 10785.83821242682, 10842.620508282173, 11197.552097915992, 11332.943335283271, 6781.62701051437, 8979.416337938013, 9143.49710765901, 9161.679341223025, 9338.813223735624, 10030.0, 10063.295569310225, 10123.493825308706, 10132.746681333025, 10177.18582513397, 3383.983080084584, 3794.29805485986, 4058.228936502055, 4188.308929026676, 4199.160167966419, 903.8192361527589, 915.1763412928285, 933.2160984772823, 957.3537861943161, 979.0503211884461, 20649.690254646193, 21868.45328866759, 21887.78112218889, 21961.23135690243, 21986.0, 957.482959202037, 984.450273790885, 986.1863962231188, 988.6935050134481, 994.5524513968743, 10929.0, 11452.0, 11480.885191147981, 11499.0, 11535.826962595567, 724879.0, 728528.0, 752407.0, 762707.0, 779811.0, 138618.0, 162738.0, 168306.31693682907, 171863.0, 173580.0, 1222.1139673736648, 1256.616731896762, 1277.1251692590606, 1279.4242590834162, 1280.295837289487, 18744.343947961755, 18926.3375781847, 19136.138873750435, 19166.520836979187, 19225.423237302904, 36534.8173259132, 62351.06473402812, 63963.04055439265, 64912.0, 65286.34713652803, 91419.62870556815, 92479.0, 92618.53690731504, 93027.13945721183, 94271.52864235635, 11571.6528504145, 11735.23720958135, 11848.76302473963, 11873.406329683308, 12094.334811585448, 11929.701757456132, 13444.798328025283, 13640.454381825013, 13741.381637826122, 13854.584362469142, 11489.827652585385, 11628.18602697803, 11695.941520292345, 11837.0, 11896.881031189578, 9471.337006409485, 10532.36805791655, 10735.785284294427, 10854.945725271324, 11099.833502497631, 9529.904700952902, 10459.68620941373, 10996.890031099907, 11099.416896532597, 11871.753465886302, 10166.491675416348, 11652.53389864396, 11818.940905295418, 11910.583129590423, 11998.700032499257, 9950.955149709176, 10142.746431339276, 11537.423128843691, 11734.061275098142, 11897.988670962719], "value": 12021.379136240235, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1611.2346635348204, 1612.8468145276124, 1640.52424796809, 1652.611636265477, 1658.07976573002, 9953.850692239605, 10179.0, 11381.715457113576, 11773.70565735856, 11997.040236781058, 9928.808542974835, 11149.275297105682, 11532.827007594893, 11557.01765349944, 11617.593384231554, 2414.7102347718296, 2463.9137630182945, 2469.074097213545, 2470.1354525915876, 2470.8270421070533, 2415.3696255027876, 2461.643061756053, 2465.9876700616473, 2466.34641819917, 2472.530219258344, 2421.3583400398907, 2428.344347026302, 2436.2569416328015, 2444.168982545934, 2457.1768457566736, 2423.491066875958, 2431.987840060806, 2434.8782560871887, 2437.878106094697, 2445.730969593343, 2368.0764501844296, 2389.020501594338, 2389.474315650554, 2393.593089174827, 2398.7241467231315, 11615.535378584846, 11835.171537992344, 11854.170208085437, 11856.881431185664, 11860.644180674593, 5845.88308233833, 5960.970195148997, 6012.0, 6030.216071910668, 6035.336113027563, 3584.7490675652716, 3672.5042119313816, 3674.540682414698, 3691.6492933171307, 3721.813909304551, 1259.4899065878335, 1262.4634530324668, 1299.2529295654942, 1309.796981467874, 1316.1050485669741, 15906.602334941603, 15923.840761592468, 15963.84036159635, 15972.0, 15974.840251597452, 4733.2900064989835, 4940.333055037524, 4985.601151907838, 5000.374953130859, 5013.799448022111, 13522.729545409036, 13547.25490098054, 13558.457661693421, 13567.525136620368, 13567.660808479866, 5967.6419414835245, 6033.909491357633, 6056.485198758067, 6062.211912451353, 6075.27096748393, 5200.713960732196, 5320.414754377015, 5378.650387724785, 5382.838514844561, 5399.136138217867, 4018.3972404139613, 4121.505419349697, 4158.607350096669, 4203.096334288142, 4235.23765722173, 1124.6401151631562, 1128.700894263021, 1129.1023636209234, 1135.920485566019, 1138.1918837625378, 842.5534466732641, 925.5927391947522, 939.9765005874908, 955.8518429643416, 998.8052329795765, 6494.512911531654, 6613.801585952429, 6614.801555953329, 6718.697658605374, 6727.495437842083, 1010.833212519926, 1060.2207377577474, 1077.4074259157433, 1086.299336927693, 1087.6519513755682, 4282.336237883133, 4292.0, 4311.374850646702, 4454.37638730578, 4466.754328511963, 14150.504732334524, 14263.07290026145, 14663.48677796272, 14666.56000319992, 14715.0, 14268.143911365143, 14279.500652431345, 14596.197209153386, 14654.194019328826, 14680.486182983544, 9975.501224938724, 10764.730881728019, 10854.620088296871, 10873.401962892116, 10901.509432075418, 6573.5398522103, 6649.135612370361, 6690.966545167437, 6728.394444500019, 6776.119104516381, 12476.001919846572, 12551.87448125507, 12850.807237891626, 12874.935625321814, 12884.48462061507, 11586.420678966186, 11683.00694440982, 12896.871031289942, 13024.283664398548, 13042.934785326013, 8685.174908383693, 8938.41900276506, 8967.417117887317, 9027.0, 9031.322650801085, 10725.570977160825, 10734.83897741519, 10782.245242832927, 10817.675469735921, 10830.945845270724, 9158.725238242863, 9205.125513076247, 9240.122188392092, 9426.62293508252, 9457.290703197425, 8985.921689397313, 8992.955035224782, 9004.954975225082, 9112.589933453142, 9267.86098208514, 8115.594220288844, 8142.8778568322705, 8199.836003280021, 8224.013118425828, 8229.382796290422, 5934.762609495745, 5948.821535353946, 5951.0, 6144.0, 6161.599496032743, 5293.814716484904, 5964.343922168645, 6139.416755408229, 6167.013277875519, 6273.843153921189], "value": 5336.546044276023, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.4587334162212, 399.63832731378096, 399.6622853688632, 402.5411031424177, 404.6297637661538, 5146.485351464861, 5274.597884364583, 5502.614816962814, 5528.742867991644, 5609.887802243953, 3987.740796848203, 4697.765111744417, 4987.301777751118, 5070.909754402803, 5362.8123015694455, 3776.7545109567927, 4725.558704595097, 4737.265723812815, 4761.976190119062, 4820.566149046585, 4275.289884046375, 4439.934415740231, 4570.268756998881, 4793.592544633693, 4823.927641085386, 3933.033483258371, 3933.5083114610675, 4306.105313661994, 4486.685931984763, 4781.354517140194, 3841.8847434577006, 4138.6482149017365, 4177.561356057616, 4190.5809419058005, 4447.777611119431, 3753.7530036518488, 3845.288621605012, 4355.368471571636, 4374.912501749947, 4568.862934111982, 4735.668503204777, 5376.784928602851, 5400.540954018913, 5401.0, 5602.971985140089, 5412.69960266885, 5435.945640543623, 5448.863778405532, 5544.279243698294, 5569.303837020372, 5860.326062502824, 5918.845825064114, 5932.584719069667, 5951.434613711691, 5969.522438204933, 4837.0, 4918.680285781413, 5009.699418034893, 5023.191651005635, 5081.212412076134, 5054.660514963501, 5474.698891560963, 5639.379668236491, 6169.7532098716, 6251.187345645082, 4439.64482841372, 4505.279155335131, 4655.975685349218, 5027.472115427828, 5198.960207958424, 4858.902821943541, 5021.799128034911, 5389.946100539023, 5402.540784033401, 5405.837824865261, 5285.577153827685, 5476.507114359647, 5509.917351239735, 5613.298337707786, 5717.4854263115685, 4631.814727410933, 5738.368779434259, 5766.481016708516, 5812.767489300465, 5880.823575292662, 1552.099782126364, 1555.949733929593, 1696.5843368374826, 1830.9725354119698, 1835.8709393722763, 1032.4063663393579, 1130.5138790320077, 1229.8770122987808, 1241.6399244219076, 1347.7129341478865, 4979.526944940225, 5465.508104270635, 5479.342478902558, 5516.393196748354, 5591.7483713233005, 5283.041496761825, 5445.727713614304, 5629.155626656035, 5858.736356863951, 6072.483838873657, 4814.229723244334, 5128.974355128201, 5265.97206606203, 5525.254090697785, 5535.446455354432, 2648.47030593882, 2804.8176868503483, 3029.409265193266, 3122.282744490521, 3158.25232048115, 5586.6871285247935, 5821.721035039242, 6214.689265536705, 6271.6550589718, 6292.685365731695, 5721.713914304351, 5847.766089356463, 5954.950633569128, 6065.636061836304, 6184.907226391608, 5269.762860671279, 5346.385165705916, 5559.861003474945, 6113.877722445526, 6219.32078338855, 4997.450280469218, 5444.727763611724, 5558.97220513911, 5568.248286481436, 6041.9395806041375, 5402.919416116871, 5762.4525670061275, 5831.550748685647, 6018.398160183947, 6065.60573562717, 5163.690178589297, 5552.750126244409, 5737.454941780691, 6211.757648470414, 6270.460463512285, 3979.3633018716864, 4185.718783263068, 4256.233877902007, 4523.647703194386, 4555.362249285036, 5817.283901249057, 5995.910061348996, 6033.728482218398, 6071.7571297147615, 6162.753489860355, 4687.601553867959, 5783.450572195635, 5843.678597677169, 5863.472287494146, 5897.498712609477, 4853.830115945925, 5382.67703937765, 5593.0, 5796.565257605781, 5837.5329973601165, 4968.732973091838, 5369.221462887781, 5704.09091363416, 5726.370099289158, 5762.4525670061275, 4501.07727915783, 5451.37309209446, 5574.777008919597, 5705.28998725602, 5822.272215973004, 5137.537621614073, 5590.972045139748, 5664.830055098354, 5734.283214598176, 5850.64896106235], "value": 4488.291020727637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.3352895637191, 766.0424469413233, 802.4022103532869, 873.4060838629731, 889.6041261638571, 14320.498782542612, 18192.727109093375, 18363.449096527092, 18429.631407371846, 18465.35371262006, 15481.303341349638, 16288.592785180377, 16405.42581009665, 16458.75311870323, 16496.092714900675, 878.7363790862747, 888.5868071346822, 1002.7894142230141, 1026.209818439801, 1026.5431882812152, 919.9448033118001, 1020.6019652335596, 1033.168299518887, 1034.7154532503546, 1035.5909415780761, 839.559231403512, 1029.0943969307, 1060.247224470623, 1060.8249638809625, 1067.0076828549445, 1056.0548309263168, 1060.5280650110703, 1060.7560261139943, 1060.7719340341864, 1061.6761887624348, 780.4692808889953, 784.5802495664849, 785.7799816051514, 786.8308313712522, 789.9091604465502, 17738.556536086573, 17801.7329740054, 17935.820641793674, 18284.268629254813, 18509.444716658523, 14806.629834254121, 15072.472463463726, 15073.623159421102, 15154.696906061818, 15184.240787960558, 11343.205975581712, 11943.9847612953, 11954.462049207807, 12158.51365945361, 12305.93847030759, 4499.932501012488, 4553.271476563734, 4559.270516717343, 4681.110588988081, 4826.420829500447, 91605.0, 99148.51277230847, 109457.0, 110142.59429622805, 114290.42854785673, 9675.854862177073, 11336.206465547333, 11403.144764142722, 11577.479013444246, 11972.760544789055, 22469.662955055686, 22922.541549168924, 23109.422264443525, 23136.0, 23188.65217021746, 8171.79570510742, 8171.79570510742, 8195.754127376189, 8198.672053117809, 8446.493210407272, 4077.959220407817, 4447.3329000650165, 4660.883477913079, 4696.178168820511, 4771.928421073687, 3552.4848896909803, 3607.85568577254, 3608.891733248007, 3623.655752703489, 3626.5648122225502, 542.818155917772, 624.685037995014, 645.9192600924885, 667.9198496180489, 670.6881300195432, 519.8778287102527, 540.9350877894677, 548.8490665067138, 573.8938296415176, 583.4981915552621, 10393.0, 10511.106555942677, 10630.574777008987, 10973.451327433597, 10990.890091098987, 620.4106099205683, 653.6731634182909, 653.7319698923419, 674.4132604634, 683.8905775076064, 4733.763311834395, 5135.6918584885025, 5144.43411224765, 5157.8452646420665, 5171.767270472763, 108796.0, 110928.0, 112920.0, 112994.43502782434, 114281.0, 71025.64487177531, 71732.0, 94132.64668383208, 94226.0, 96621.0, 68010.65994669995, 85117.14882851092, 86153.56923215344, 87004.56497717471, 88835.0, 9871.50642467888, 9922.454265015493, 9934.503274836374, 9949.403035817873, 9949.552270148006, 49533.50466495289, 55687.0, 56128.5967850807, 56905.86188276294, 57993.420065798804, 70931.0, 71841.0, 72058.0, 72903.0, 73314.0, 11299.0, 11568.363739994045, 11622.0, 11674.0, 11693.356865372487, 12452.875471245534, 12523.874761252271, 12652.30412327294, 13184.011199160293, 13800.102993305403, 11735.61218333839, 12036.458359374024, 12036.819447708116, 12065.879341206475, 12152.635420937386, 11561.364124972872, 11882.28706277626, 11925.34410607425, 11926.463309150935, 12068.698282543006, 11314.157019242322, 11672.649820505398, 11954.10344224204, 12083.214591051905, 12176.817347739614, 8826.338024648048, 8892.955535222283, 9136.497492637704, 9248.0, 9449.669261576088, 9442.48066356357, 9715.902840971783, 9886.110250077527, 10041.945595712345, 10060.849087263843], "value": 7719.248950049489, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 109.0, 170.0, 174.0, 168.0, 122.0, 118.0, 144.0, 112.0, 144.0], "value": 136.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 103.0, 97.0, 164.0, 155.0, 136.0, 112.0, 110.0, 139.0, 139.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 192.0, 235.0, 272.0, 206.0, 197.0, 198.0, 195.0, 195.0, 215.0], "value": 210.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 57.0, 86.0, 60.0, 61.0, 62.0, 58.0, 72.0, 60.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 83.0, 70.0, 71.0, 71.0, 81.0, 69.0, 70.0, 79.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 133.0, 131.0, 88.0, 88.0, 101.0, 90.0, 88.0, 89.0, 89.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 104.0, 96.0, 95.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 135.0, 91.0, 94.0, 108.0, 91.0, 96.0, 103.0, 91.0, 93.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 68.0, 68.0, 69.0, 67.0, 68.0, 69.0, 68.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 66.0, 70.0, 98.0, 90.0, 61.0, 59.0, 57.0, 59.0, 61.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 75.0, 99.0, 96.0, 97.0, 86.0, 82.0, 82.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 144.0, 139.0, 140.0, 140.0, 134.0, 146.0, 162.0, 190.0, 174.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 181.0, 219.0, 199.0, 159.0, 160.0, 163.0, 163.0, 164.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 59.0, 65.0, 64.0, 65.0, 63.0, 64.0, 65.0, 64.0, 63.0], "value": 63.8, "unit": "score"}]}]} 13:51:41 INFO - Return code: 0 13:51:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:51:41 ERROR - # TBPL SUCCESS # 13:51:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:51:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:51:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:51:41 INFO - 2017-05-19 13:51:41.649 screenresolution[1665:10663] starting screenresolution argv=screenresolution get 13:51:41 INFO - 2017-05-19 13:51:41.651 screenresolution[1665:10663] Display 0: 1600x1200x32@60 13:51:41 INFO - 2017-05-19 13:51:41.657 screenresolution[1666:10666] starting screenresolution argv=screenresolution list 13:51:41 INFO - Available Modes on Display 0 13:51:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:51:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:51:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:51:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:51:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:51:41 INFO - Intel Iris: 13:51:41 INFO - Chipset Model: Intel Iris 13:51:41 INFO - Type: GPU 13:51:41 INFO - Bus: Built-In 13:51:41 INFO - VRAM (Dynamic, Max): 1536 MB 13:51:41 INFO - Vendor: Intel (0x8086) 13:51:41 INFO - Device ID: 0x0a2e 13:51:41 INFO - Revision ID: 0x0009 13:51:41 INFO - Displays: 13:51:41 INFO - Display: 13:51:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:51:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:51:41 INFO - Display Serial Number: 1600x1200 60 13:51:41 INFO - Main Display: Yes 13:51:41 INFO - Mirror: Off 13:51:41 INFO - Online: Yes 13:51:41 INFO - Rotation: Supported 13:51:41 INFO - Return code: 0 13:51:41 INFO - Running post-action listener: _resource_record_post_action 13:51:41 INFO - [mozharness: 2017-05-19 20:51:41.893655Z] Finished run-tests step (success) 13:51:41 INFO - Running post-run listener: _resource_record_post_run 13:51:41 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 39983104; Write bytes: 424967168; Read time: 255; Write time: 1426 13:51:41 INFO - TinderboxPrint: CPU usage
54.5% 13:51:41 INFO - TinderboxPrint: I/O read bytes / time
39,983,104 / 255 13:51:41 INFO - TinderboxPrint: I/O write bytes / time
424,967,168 / 1,426 13:51:41 INFO - TinderboxPrint: CPU idle
2,167.9 (45.5%) 13:51:41 INFO - TinderboxPrint: CPU system
956.8 (20.1%) 13:51:41 INFO - TinderboxPrint: CPU user
1,641.2 (34.4%) 13:51:41 INFO - TinderboxPrint: Swap in / out
162,455,552 / 0 13:51:41 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 117465600; Write bytes: 118982656; Read time: 11015; Write time: 262 13:51:41 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 37935104; Write bytes: 299262976; Read time: 236; Write time: 1138 13:51:42 INFO - Running post-run listener: _upload_blobber_files 13:51:42 INFO - Blob upload gear active. 13:51:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:42 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 13:51:42 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'] 13:51:42 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 13:51:42 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:51:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:51:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:43 INFO - (blobuploader) - INFO - Done attempting. 13:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:51:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:44 INFO - (blobuploader) - INFO - Done attempting. 13:51:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:51:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:51:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:45 INFO - (blobuploader) - INFO - Done attempting. 13:51:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:51:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:51:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:47 INFO - (blobuploader) - INFO - Done attempting. 13:51:47 INFO - (blobuploader) - INFO - Iteration through files over. 13:51:47 INFO - Return code: 0 13:51:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:51:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:51:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be2d89352ac522ac9da45fc02b7fd25d4b30032f2685f95cfbfba50cc90d95e2b147f720f6d20688ccbfb0128996f8ff785046870dcf65b186702bf962bc45fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc72d011e2af67fcaf9d4c5b1446a18c39eb361cdfe7716ab0a0be2f3c0a9da80c1c5eed45853bf0643b0bab50d9cf6c0a3a4ee6ad4a722db91d2df1fd9f8b68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/886ffcfd7de90b7eb60a6b8b8a85bf3855815ef6a06f3b5cc569c1fb115ed2d7251039af27e8c8e5f8a3507f87c3fe7ff0d8e2571456e86f26e8abb24a7772fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c57a37079581d3cd747630d84b4bd4a113cd7f665748585935b7484f3b44e24b86bdf3979d832857afac07e3a29535d0d470c728fceae5d7ce3468c3d7b8edb"} 13:51:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:51:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:51:47 INFO - Contents: 13:51:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/be2d89352ac522ac9da45fc02b7fd25d4b30032f2685f95cfbfba50cc90d95e2b147f720f6d20688ccbfb0128996f8ff785046870dcf65b186702bf962bc45fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc72d011e2af67fcaf9d4c5b1446a18c39eb361cdfe7716ab0a0be2f3c0a9da80c1c5eed45853bf0643b0bab50d9cf6c0a3a4ee6ad4a722db91d2df1fd9f8b68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/886ffcfd7de90b7eb60a6b8b8a85bf3855815ef6a06f3b5cc569c1fb115ed2d7251039af27e8c8e5f8a3507f87c3fe7ff0d8e2571456e86f26e8abb24a7772fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c57a37079581d3cd747630d84b4bd4a113cd7f665748585935b7484f3b44e24b86bdf3979d832857afac07e3a29535d0d470c728fceae5d7ce3468c3d7b8edb"} 13:51:47 INFO - Running post-run listener: copy_logs_to_upload_dir 13:51:47 INFO - Copying logs to upload dir... 13:51:47 INFO - mkdir: /builds/slave/test/build/upload/logs 13:51:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.731572 ========= master_lag: -0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-19 13:51:46.770846) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:46.774212) ========= 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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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/be2d89352ac522ac9da45fc02b7fd25d4b30032f2685f95cfbfba50cc90d95e2b147f720f6d20688ccbfb0128996f8ff785046870dcf65b186702bf962bc45fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc72d011e2af67fcaf9d4c5b1446a18c39eb361cdfe7716ab0a0be2f3c0a9da80c1c5eed45853bf0643b0bab50d9cf6c0a3a4ee6ad4a722db91d2df1fd9f8b68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/886ffcfd7de90b7eb60a6b8b8a85bf3855815ef6a06f3b5cc569c1fb115ed2d7251039af27e8c8e5f8a3507f87c3fe7ff0d8e2571456e86f26e8abb24a7772fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c57a37079581d3cd747630d84b4bd4a113cd7f665748585935b7484f3b44e24b86bdf3979d832857afac07e3a29535d0d470c728fceae5d7ce3468c3d7b8edb"} build_url:https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/YvVIw_XlQQmyMxGXMMBX9w/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/be2d89352ac522ac9da45fc02b7fd25d4b30032f2685f95cfbfba50cc90d95e2b147f720f6d20688ccbfb0128996f8ff785046870dcf65b186702bf962bc45fe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc72d011e2af67fcaf9d4c5b1446a18c39eb361cdfe7716ab0a0be2f3c0a9da80c1c5eed45853bf0643b0bab50d9cf6c0a3a4ee6ad4a722db91d2df1fd9f8b68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/886ffcfd7de90b7eb60a6b8b8a85bf3855815ef6a06f3b5cc569c1fb115ed2d7251039af27e8c8e5f8a3507f87c3fe7ff0d8e2571456e86f26e8abb24a7772fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6c57a37079581d3cd747630d84b4bd4a113cd7f665748585935b7484f3b44e24b86bdf3979d832857afac07e3a29535d0d470c728fceae5d7ce3468c3d7b8edb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:46.807274) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:46.807598) ========= 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.QfVNHFPhwq/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.OtxZ2RvEmh/Listeners TMPDIR=/var/folders/74/b6n0j0694jj3lchy6lrg3v1w00000w/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.005243 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:51:46.856649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 13:51:46.856974) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 13:51:46.857295) ========= ========= Total master_lag: 2.02 =========