builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0337 starttime: 1494782275.46 results: success (0) revision: 3a801856dea979b0c3f630e1b0ec58106cdcb380 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:55.456260) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:55.456699) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:55.456971) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:55.615780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:55.616134) ========= 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.ow6PxNaS1q/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.QbgmNdNppj/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-14 10:17:55-- 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% 26.2M=0s 2017-05-14 10:17:56 (26.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.602065 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:56.331815) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:56.332149) ========= 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.ow6PxNaS1q/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.QbgmNdNppj/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.027092 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:56.481819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:56.482297) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3a801856dea979b0c3f630e1b0ec58106cdcb380 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 3a801856dea979b0c3f630e1b0ec58106cdcb380 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ow6PxNaS1q/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.QbgmNdNppj/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-14 10:17:56,609 truncating revision to first 12 chars 2017-05-14 10:17:56,610 Setting DEBUG logging. 2017-05-14 10:17:56,610 attempt 1/10 2017-05-14 10:17:56,610 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3a801856dea9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-14 10:17:57,049 unpacking tar archive at: mozilla-central-3a801856dea9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.725882 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:57.248178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:57.248474) ========= script_repo_revision: 3a801856dea979b0c3f630e1b0ec58106cdcb380 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:57.251879) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:57.252150) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 10:17:57.277844) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-14 10:17:57.278141) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ow6PxNaS1q/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.QbgmNdNppj/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:17:57 INFO - MultiFileLogger online at 20170514 10:17:57 in /builds/slave/test 10:17:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:17:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:17:57 INFO - {'append_to_log': False, 10:17:57 INFO - 'base_work_dir': '/builds/slave/test', 10:17:57 INFO - 'blob_upload_branch': 'Firefox', 10:17:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:17:57 INFO - 'branch': 'Firefox', 10:17:57 INFO - 'buildbot_json_path': 'buildprops.json', 10:17:57 INFO - 'config_files': ('talos/mac_config.py',), 10:17:57 INFO - 'default_actions': ('clobber', 10:17:57 INFO - 'read-buildbot-config', 10:17:57 INFO - 'download-and-extract', 10:17:57 INFO - 'populate-webroot', 10:17:57 INFO - 'create-virtualenv', 10:17:57 INFO - 'install', 10:17:57 INFO - 'run-tests'), 10:17:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:17:57 INFO - 'download_minidump_stackwalk': True, 10:17:57 INFO - 'download_symbols': 'ondemand', 10:17:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:17:57 INFO - 'tooltool.py': '/tools/tooltool.py', 10:17:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:17:57 INFO - '/tools/misc-python/virtualenv.py')}, 10:17:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:17:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:17:57 INFO - 'gecko_profile': False, 10:17:57 INFO - 'gecko_profile_interval': 0, 10:17:57 INFO - 'installer_path': 'installer.exe', 10:17:57 INFO - 'log_level': 'info', 10:17:57 INFO - 'log_name': 'talos', 10:17:57 INFO - 'log_to_console': True, 10:17:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:17:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:17:57 INFO - 'opt_config_files': (), 10:17:57 INFO - 'pip_index': False, 10:17:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:17:57 INFO - 'cmd': ('bash', 10:17:57 INFO - '-c', 10:17:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:17:57 INFO - 'enabled': True, 10:17:57 INFO - 'halt_on_failure': False, 10:17:57 INFO - 'name': 'check_screen_resolution'},), 10:17:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:17:57 INFO - 'cmd': ('bash', 10:17:57 INFO - '-c', 10:17:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:17:57 INFO - 'enabled': True, 10:17:57 INFO - 'halt_on_failure': False, 10:17:57 INFO - 'name': 'check_screen_resolution'},), 10:17:57 INFO - 'run_cmd_checks_enabled': True, 10:17:57 INFO - 'suite': 'dromaeojs-e10s', 10:17:57 INFO - 'system_bits': '32', 10:17:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:17:57 INFO - 'title': 't-yosemite-r7-0337', 10:17:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:17:57 INFO - 'use_talos_json': True, 10:17:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:17:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:17:57 INFO - 'work_dir': 'build'} 10:17:57 INFO - [mozharness: 2017-05-14 17:17:57.493949Z] Running clobber step. 10:17:57 INFO - Running pre-action listener: _resource_record_pre_action 10:17:57 INFO - Running main action method: clobber 10:17:57 INFO - rmtree: /builds/slave/test/build 10:17:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:17:59 INFO - Running post-action listener: _resource_record_post_action 10:17:59 INFO - [mozharness: 2017-05-14 17:17:59.938867Z] Finished clobber step (success) 10:17:59 INFO - [mozharness: 2017-05-14 17:17:59.939001Z] Running read-buildbot-config step. 10:17:59 INFO - Running pre-action listener: _resource_record_pre_action 10:17:59 INFO - Running main action method: read_buildbot_config 10:17:59 INFO - Using buildbot properties: 10:17:59 INFO - { 10:17:59 INFO - "project": "", 10:17:59 INFO - "product": "firefox", 10:17:59 INFO - "who": "archaeopteryx@coole-files.de", 10:17:59 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:17:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:17:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:17:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:17:59 INFO - "stage_platform": "macosx64", 10:17:59 INFO - "basedir": "/builds/slave/test", 10:17:59 INFO - "buildnumber": 143, 10:17:59 INFO - "platform": "yosemite_r7", 10:17:59 INFO - "parent_task_id": "Ihp-PaV5RrOEZPxmpLz0Bw", 10:17:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:17:59 INFO - "slavebuilddir": "test", 10:17:59 INFO - "taskId": "D8Fv9mI2SKyIkU6CxgFdDQ", 10:17:59 INFO - "branch": "mozilla-central", 10:17:59 INFO - "script_repo_revision": "production", 10:17:59 INFO - "revision": "3a801856dea979b0c3f630e1b0ec58106cdcb380", 10:17:59 INFO - "slavename": "t-yosemite-r7-0337", 10:17:59 INFO - "repo_path": "mozilla-central" 10:17:59 INFO - } 10:17:59 INFO - Finding installer, test and symbols from parent task. 10:17:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8Fv9mI2SKyIkU6CxgFdDQ'}, attempt #1 10:18:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D8Fv9mI2SKyIkU6CxgFdDQ'}, attempt #1 10:18:01 INFO - Task dependencies: ScwmhT_WTXufDH_z3yqtIg 10:18:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw'}, attempt #1 10:18:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:18:01 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/test_packages.json 10:18:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/target.crashreporter-symbols.zip 10:18:01 INFO - Running post-action listener: _resource_record_post_action 10:18:01 INFO - [mozharness: 2017-05-14 17:18:01.646801Z] Finished read-buildbot-config step (success) 10:18:01 INFO - [mozharness: 2017-05-14 17:18:01.646922Z] Running download-and-extract step. 10:18:01 INFO - Running pre-action listener: _resource_record_pre_action 10:18:01 INFO - Running main action method: download_and_extract 10:18:01 INFO - mkdir: /builds/slave/test/build/tests 10:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:01 INFO - trying https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:18:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:18:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:18:02 INFO - Downloaded 1325 bytes. 10:18:02 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:18:02 INFO - Using the following test package requirements: 10:18:02 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:18:02 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:18:02 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:18:02 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:18:02 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'jsshell-mac.zip'], 10:18:02 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:18:02 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:18:02 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:18:02 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:18:02 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:18:02 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:02 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:18:02 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:18:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:18:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:18:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:18:03 INFO - Content-Length response header: 56563422 10:18:03 INFO - Bytes received: 56563422 10:18:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:18:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:18:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:18:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:18:23 INFO - Content-Length response header: 56563422 10:18:23 INFO - Bytes received: 56563422 10:18:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:18:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:18:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:18:41 INFO - Content-Length response header: 14409411 10:18:41 INFO - Bytes received: 14409411 10:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:41 INFO - trying https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:18:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:18:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:18:44 INFO - Downloaded 75910705 bytes. 10:18:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:18:44 INFO - mkdir: /builds/slave/test/properties 10:18:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:18:44 INFO - Writing to file /builds/slave/test/properties/build_url 10:18:44 INFO - Contents: 10:18:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:18:44 INFO - Running post-action listener: _resource_record_post_action 10:18:44 INFO - Running post-action listener: set_extra_try_arguments 10:18:44 INFO - [mozharness: 2017-05-14 17:18:44.083869Z] Finished download-and-extract step (success) 10:18:44 INFO - [mozharness: 2017-05-14 17:18:44.084018Z] Running populate-webroot step. 10:18:44 INFO - Running pre-action listener: _resource_record_pre_action 10:18:44 INFO - Running main action method: populate_webroot 10:18:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:18:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:18:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:18:44 INFO - '--tppagecycles', 10:18:44 INFO - '7'], 10:18:44 INFO - 'tests': ['tsvgm']}}, 10:18:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:18:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:18:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:18:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:18:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:18:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['damp', 'tps']}, 10:18:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'tests': ['damp', 'tps']}, 10:18:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['dromaeo_dom']}, 10:18:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:18:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:18:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:18:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['a11yr', 10:18:44 INFO - 'ts_paint', 10:18:44 INFO - 'tpaint', 10:18:44 INFO - 'sessionrestore', 10:18:44 INFO - 'sessionrestore_no_auto_restore', 10:18:44 INFO - 'tabpaint']}, 10:18:44 INFO - 'other-e10s': {'tests': ['a11yr', 10:18:44 INFO - 'ts_paint', 10:18:44 INFO - 'tpaint', 10:18:44 INFO - 'sessionrestore', 10:18:44 INFO - 'sessionrestore_no_auto_restore', 10:18:44 INFO - 'tabpaint']}, 10:18:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:18:44 INFO - 'ts_paint', 10:18:44 INFO - 'tpaint', 10:18:44 INFO - 'sessionrestore', 10:18:44 INFO - 'sessionrestore_no_auto_restore', 10:18:44 INFO - 'tabpaint']}, 10:18:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:18:44 INFO - 'ts_paint', 10:18:44 INFO - 'tpaint', 10:18:44 INFO - 'sessionrestore', 10:18:44 INFO - 'sessionrestore_no_auto_restore', 10:18:44 INFO - 'tabpaint']}, 10:18:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['a11yr', 10:18:44 INFO - 'ts_paint', 10:18:44 INFO - 'tpaint', 10:18:44 INFO - 'sessionrestore', 10:18:44 INFO - 'sessionrestore_no_auto_restore', 10:18:44 INFO - 'tabpaint']}, 10:18:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['a11yr', 10:18:44 INFO - 'ts_paint', 10:18:44 INFO - 'tpaint', 10:18:44 INFO - 'sessionrestore', 10:18:44 INFO - 'sessionrestore_no_auto_restore', 10:18:44 INFO - 'tabpaint']}, 10:18:44 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:18:44 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:18:44 INFO - 'bloom_basic_ref']}, 10:18:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['tsvgx', 10:18:44 INFO - 'tsvgr_opacity', 10:18:44 INFO - 'tart', 10:18:44 INFO - 'tscrollx', 10:18:44 INFO - 'cart', 10:18:44 INFO - 'tsvg_static']}, 10:18:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:18:44 INFO - 'tsvgr_opacity', 10:18:44 INFO - 'tart', 10:18:44 INFO - 'tscrollx', 10:18:44 INFO - 'cart', 10:18:44 INFO - 'tsvg_static']}, 10:18:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'talos_options': ['--disable-e10s'], 10:18:44 INFO - 'tests': ['tp5o']}, 10:18:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:18:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'talos_options': ['--disable-e10s', 10:18:44 INFO - '--xperf_path', 10:18:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:44 INFO - 'tests': ['tp5n']}, 10:18:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:18:44 INFO - 'talos_options': ['--xperf_path', 10:18:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:18:44 INFO - 'tests': ['tp5n']}}, 10:18:44 INFO - 'talos.zip': {'path': '', 10:18:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:18:44 INFO - Running post-action listener: _resource_record_post_action 10:18:44 INFO - [mozharness: 2017-05-14 17:18:44.104776Z] Finished populate-webroot step (success) 10:18:44 INFO - [mozharness: 2017-05-14 17:18:44.104914Z] Running create-virtualenv step. 10:18:44 INFO - Running pre-action listener: _resource_record_pre_action 10:18:44 INFO - Running main action method: create_virtualenv 10:18:44 INFO - Creating virtualenv /builds/slave/test/build/venv 10:18:44 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 10:18:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:18:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:18:44 INFO - Using real prefix '/tools/python27' 10:18:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:18:44 INFO - Installing distribute.............................................................................................................................................................................................done. 10:18:47 INFO - Installing pip.................done. 10:18:47 INFO - Return code: 0 10:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:18:48 INFO - Reading from file tmpfile_stdout 10:18:48 INFO - Output received: 10:18:48 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) 10:18:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:18:48 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')]} 10:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:48 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')]} 10:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:48 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:48 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 10:18:48 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 10:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:48 INFO - 'HOME': '/Users/cltbld', 10:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:48 INFO - 'LOGNAME': 'cltbld', 10:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:48 INFO - 'MOZ_NO_REMOTE': '1', 10:18:48 INFO - 'NO_EM_RESTART': '1', 10:18:48 INFO - 'PAGER': '/bin/cat', 10:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:48 INFO - 'PWD': '/builds/slave/test', 10:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:48 INFO - 'SHELL': '/bin/bash', 10:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:48 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:48 INFO - 'USER': 'cltbld', 10:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:48 INFO - 'XPC_FLAGS': '0x0', 10:18:48 INFO - 'XPC_SERVICE_NAME': '0', 10:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:48 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 10:18:48 INFO - Cleaning up... 10:18:48 INFO - Return code: 0 10:18:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:18:48 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')]} 10:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:48 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')]} 10:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:48 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:48 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 10:18:48 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 10:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:48 INFO - 'HOME': '/Users/cltbld', 10:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:48 INFO - 'LOGNAME': 'cltbld', 10:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:48 INFO - 'MOZ_NO_REMOTE': '1', 10:18:48 INFO - 'NO_EM_RESTART': '1', 10:18:48 INFO - 'PAGER': '/bin/cat', 10:18:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:48 INFO - 'PWD': '/builds/slave/test', 10:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:48 INFO - 'SHELL': '/bin/bash', 10:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:48 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:48 INFO - 'USER': 'cltbld', 10:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:48 INFO - 'XPC_FLAGS': '0x0', 10:18:48 INFO - 'XPC_SERVICE_NAME': '0', 10:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:48 INFO - Downloading/unpacking psutil>=3.1.1 10:18:48 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 10:18:48 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 10:18:48 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 10:18:48 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 10:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:18:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:50 INFO - Installing collected packages: psutil 10:18:50 INFO - Running setup.py install for psutil 10:18:51 INFO - building 'psutil._psutil_osx' extension 10:18:51 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 10:18:51 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 10:18:51 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 10:18:51 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 10:18:51 INFO - building 'psutil._psutil_posix' extension 10:18:51 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 10:18:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:18:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:18:51 INFO - ^ 10:18:51 INFO - 1 warning generated. 10:18:51 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 10:18:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:18:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:18:52 INFO - Successfully installed psutil 10:18:52 INFO - Cleaning up... 10:18:52 INFO - Return code: 0 10:18:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:52 INFO - 'HOME': '/Users/cltbld', 10:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:52 INFO - 'LOGNAME': 'cltbld', 10:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:52 INFO - 'MOZ_NO_REMOTE': '1', 10:18:52 INFO - 'NO_EM_RESTART': '1', 10:18:52 INFO - 'PAGER': '/bin/cat', 10:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:52 INFO - 'PWD': '/builds/slave/test', 10:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:52 INFO - 'SHELL': '/bin/bash', 10:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:52 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:52 INFO - 'USER': 'cltbld', 10:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:52 INFO - 'XPC_FLAGS': '0x0', 10:18:52 INFO - 'XPC_SERVICE_NAME': '0', 10:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:18:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:18:53 INFO - Installing collected packages: mozsystemmonitor 10:18:53 INFO - Running setup.py install for mozsystemmonitor 10:18:54 INFO - Successfully installed mozsystemmonitor 10:18:54 INFO - Cleaning up... 10:18:54 INFO - Return code: 0 10:18:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:18:54 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')]} 10:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:54 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')]} 10:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:54 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:54 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 10:18:54 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 10:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:54 INFO - 'HOME': '/Users/cltbld', 10:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:54 INFO - 'LOGNAME': 'cltbld', 10:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:54 INFO - 'MOZ_NO_REMOTE': '1', 10:18:54 INFO - 'NO_EM_RESTART': '1', 10:18:54 INFO - 'PAGER': '/bin/cat', 10:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:54 INFO - 'PWD': '/builds/slave/test', 10:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:54 INFO - 'SHELL': '/bin/bash', 10:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:54 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:54 INFO - 'USER': 'cltbld', 10:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:54 INFO - 'XPC_FLAGS': '0x0', 10:18:54 INFO - 'XPC_SERVICE_NAME': '0', 10:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:54 INFO - Downloading/unpacking jsonschema==2.5.1 10:18:54 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 10:18:54 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 10:18:54 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 10:18:54 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 10:18:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:18:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:55 INFO - Downloading functools32-3.2.3-2.tar.gz 10:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:18:56 INFO - warning: no files found matching '*.txt' 10:18:56 INFO - no previously-included directories found matching 'build' 10:18:56 INFO - no previously-included directories found matching 'dist' 10:18:56 INFO - no previously-included directories found matching '.git*' 10:18:56 INFO - Installing collected packages: jsonschema, functools32 10:18:56 INFO - Running setup.py install for functools32 10:18:56 INFO - warning: no files found matching '*.txt' 10:18:56 INFO - no previously-included directories found matching 'build' 10:18:56 INFO - no previously-included directories found matching 'dist' 10:18:56 INFO - no previously-included directories found matching '.git*' 10:18:56 INFO - Successfully installed jsonschema functools32 10:18:56 INFO - Cleaning up... 10:18:56 INFO - Return code: 0 10:18:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:56 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:56 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 10:18:56 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 10:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:56 INFO - 'HOME': '/Users/cltbld', 10:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:56 INFO - 'LOGNAME': 'cltbld', 10:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:56 INFO - 'MOZ_NO_REMOTE': '1', 10:18:56 INFO - 'NO_EM_RESTART': '1', 10:18:56 INFO - 'PAGER': '/bin/cat', 10:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:56 INFO - 'PWD': '/builds/slave/test', 10:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:56 INFO - 'SHELL': '/bin/bash', 10:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:56 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:56 INFO - 'USER': 'cltbld', 10:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:56 INFO - 'XPC_FLAGS': '0x0', 10:18:56 INFO - 'XPC_SERVICE_NAME': '0', 10:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:18:56 INFO - Cleaning up... 10:18:56 INFO - Return code: 0 10:18:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:56 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:56 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 10:18:56 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 10:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:56 INFO - 'HOME': '/Users/cltbld', 10:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:56 INFO - 'LOGNAME': 'cltbld', 10:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:56 INFO - 'MOZ_NO_REMOTE': '1', 10:18:56 INFO - 'NO_EM_RESTART': '1', 10:18:56 INFO - 'PAGER': '/bin/cat', 10:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:56 INFO - 'PWD': '/builds/slave/test', 10:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:56 INFO - 'SHELL': '/bin/bash', 10:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:56 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:56 INFO - 'USER': 'cltbld', 10:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:56 INFO - 'XPC_FLAGS': '0x0', 10:18:56 INFO - 'XPC_SERVICE_NAME': '0', 10:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:18:56 INFO - Downloading/unpacking blobuploader==1.2.4 10:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:58 INFO - Downloading blobuploader-1.2.4.tar.gz 10:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:18:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:18:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:18:58 INFO - Downloading docopt-0.6.1.tar.gz 10:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:18:59 INFO - Installing collected packages: blobuploader, requests, docopt 10:18:59 INFO - Running setup.py install for blobuploader 10:18:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:18:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:18:59 INFO - Running setup.py install for requests 10:18:59 INFO - Running setup.py install for docopt 10:18:59 INFO - Successfully installed blobuploader requests docopt 10:18:59 INFO - Cleaning up... 10:18:59 INFO - Return code: 0 10:18:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:18:59 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:18:59 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 10:18:59 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 10:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:59 INFO - 'HOME': '/Users/cltbld', 10:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:59 INFO - 'LOGNAME': 'cltbld', 10:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:59 INFO - 'MOZ_NO_REMOTE': '1', 10:18:59 INFO - 'NO_EM_RESTART': '1', 10:18:59 INFO - 'PAGER': '/bin/cat', 10:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:59 INFO - 'PWD': '/builds/slave/test', 10:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:59 INFO - 'SHELL': '/bin/bash', 10:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:18:59 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:59 INFO - 'USER': 'cltbld', 10:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:59 INFO - 'XPC_FLAGS': '0x0', 10:18:59 INFO - 'XPC_SERVICE_NAME': '0', 10:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-D0sOo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-CUGRMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-57eoTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-JyKvaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-5OS1ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-Bm0D_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-qycOlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:00 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-ROjJ4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-SRvvPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-Uoa2Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-7my5GE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-pijse1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-VyanqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-cEY_j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-asJMtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-IejNxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:01 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-pfUm6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:19:01 INFO - Running setup.py install for manifestparser 10:19:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:19:02 INFO - Running setup.py install for mozcrash 10:19:02 INFO - Running setup.py install for mozdebug 10:19:02 INFO - Running setup.py install for mozdevice 10:19:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:19:02 INFO - Running setup.py install for mozfile 10:19:02 INFO - Running setup.py install for mozhttpd 10:19:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:19:02 INFO - Running setup.py install for mozinfo 10:19:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:19:02 INFO - Running setup.py install for mozInstall 10:19:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Running setup.py install for mozleak 10:19:03 INFO - Running setup.py install for mozlog 10:19:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Running setup.py install for moznetwork 10:19:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Running setup.py install for mozprocess 10:19:03 INFO - Running setup.py install for mozprofile 10:19:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:19:03 INFO - Running setup.py install for mozrunner 10:19:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Running setup.py install for mozscreenshot 10:19:04 INFO - Running setup.py install for moztest 10:19:04 INFO - Running setup.py install for mozversion 10:19:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:19:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:19:04 INFO - Cleaning up... 10:19:04 INFO - Return code: 0 10:19:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:04 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:19:04 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 10:19:04 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 10:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:04 INFO - 'HOME': '/Users/cltbld', 10:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:04 INFO - 'LOGNAME': 'cltbld', 10:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:04 INFO - 'MOZ_NO_REMOTE': '1', 10:19:04 INFO - 'NO_EM_RESTART': '1', 10:19:04 INFO - 'PAGER': '/bin/cat', 10:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:04 INFO - 'PWD': '/builds/slave/test', 10:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:04 INFO - 'SHELL': '/bin/bash', 10:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:19:04 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:04 INFO - 'USER': 'cltbld', 10:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:04 INFO - 'XPC_FLAGS': '0x0', 10:19:04 INFO - 'XPC_SERVICE_NAME': '0', 10:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:04 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-mrKb5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:04 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)) 10:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:04 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-mCYOTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-g7NuIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-z9nVz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-mSse5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-z5qaNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-T9rKdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-7U95r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-C_MA3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-AbPYaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-JflQtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:05 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)) 10:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:05 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-ggLLeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:06 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)) 10:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:06 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-zWpfmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:06 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)) 10:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:06 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-_ruEtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:06 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)) 10:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:06 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-bc_Uf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:06 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)) 10:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:06 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-c5TDjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:06 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)) 10:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:06 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xkoXuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:06 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)) 10:19:06 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)) 10:19:06 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)) 10:19:06 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)) 10:19:06 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:06 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:06 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 10:19:06 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 10:19:06 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 10:19:06 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 10:19:07 INFO - Downloading blessings-1.6.tar.gz 10:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:19:08 INFO - Installing collected packages: blessings 10:19:08 INFO - Running setup.py install for blessings 10:19:08 INFO - Successfully installed blessings 10:19:08 INFO - Cleaning up... 10:19:08 INFO - Return code: 0 10:19:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:08 INFO - Reading from file tmpfile_stdout 10:19:08 INFO - Current package versions: 10:19:08 INFO - blessings == 1.6 10:19:08 INFO - blobuploader == 1.2.4 10:19:08 INFO - docopt == 0.6.1 10:19:08 INFO - functools32 == 3.2.3-2 10:19:08 INFO - jsonschema == 2.5.1 10:19:08 INFO - manifestparser == 1.1 10:19:08 INFO - mozInstall == 1.12 10:19:08 INFO - mozcrash == 1.0 10:19:08 INFO - mozdebug == 0.1 10:19:08 INFO - mozdevice == 0.50 10:19:08 INFO - mozfile == 1.2 10:19:08 INFO - mozhttpd == 0.7 10:19:08 INFO - mozinfo == 0.9 10:19:08 INFO - mozleak == 0.1 10:19:08 INFO - mozlog == 3.4 10:19:08 INFO - moznetwork == 0.27 10:19:08 INFO - mozprocess == 0.25 10:19:08 INFO - mozprofile == 0.28 10:19:08 INFO - mozrunner == 6.13 10:19:08 INFO - mozscreenshot == 0.1 10:19:08 INFO - mozsystemmonitor == 0.3 10:19:08 INFO - moztest == 0.8 10:19:08 INFO - mozversion == 1.4 10:19:08 INFO - psutil == 3.1.1 10:19:08 INFO - requests == 1.2.3 10:19:08 INFO - wsgiref == 0.1.2 10:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:08 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 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:19:08 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 10:19:08 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 10:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:08 INFO - 'HOME': '/Users/cltbld', 10:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:08 INFO - 'LOGNAME': 'cltbld', 10:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:08 INFO - 'MOZ_NO_REMOTE': '1', 10:19:08 INFO - 'NO_EM_RESTART': '1', 10:19:08 INFO - 'PAGER': '/bin/cat', 10:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:08 INFO - 'PWD': '/builds/slave/test', 10:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:08 INFO - 'SHELL': '/bin/bash', 10:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:19:08 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:08 INFO - 'USER': 'cltbld', 10:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:08 INFO - 'XPC_FLAGS': '0x0', 10:19:08 INFO - 'XPC_SERVICE_NAME': '0', 10:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 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)) 10:19:08 INFO - Cleaning up... 10:19:08 INFO - Return code: 0 10:19:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103911b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103914768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8da4c2640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, '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.ow6PxNaS1q/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.QbgmNdNppj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 10:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:08 INFO - 'HOME': '/Users/cltbld', 10:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:08 INFO - 'LOGNAME': 'cltbld', 10:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:08 INFO - 'MOZ_NO_REMOTE': '1', 10:19:08 INFO - 'NO_EM_RESTART': '1', 10:19:08 INFO - 'PAGER': '/bin/cat', 10:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:08 INFO - 'PWD': '/builds/slave/test', 10:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:08 INFO - 'SHELL': '/bin/bash', 10:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:19:08 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:08 INFO - 'USER': 'cltbld', 10:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:08 INFO - 'XPC_FLAGS': '0x0', 10:19:08 INFO - 'XPC_SERVICE_NAME': '0', 10:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:19:09 INFO - Cleaning up... 10:19:09 INFO - Return code: 0 10:19:09 INFO - Running post-action listener: _resource_record_post_action 10:19:09 INFO - Running post-action listener: _start_resource_monitoring 10:19:09 INFO - Starting resource monitoring. 10:19:09 INFO - [mozharness: 2017-05-14 17:19:09.206675Z] Finished create-virtualenv step (success) 10:19:09 INFO - [mozharness: 2017-05-14 17:19:09.207453Z] Running install step. 10:19:09 INFO - Running pre-action listener: _resource_record_pre_action 10:19:09 INFO - Running main action method: install 10:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:09 INFO - Reading from file tmpfile_stdout 10:19:09 INFO - Detecting whether we're running mozinstall >=1.0... 10:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:19:09 INFO - Reading from file tmpfile_stdout 10:19:09 INFO - Output received: 10:19:09 INFO - Usage: mozinstall [options] installer 10:19:09 INFO - Options: 10:19:09 INFO - -h, --help show this help message and exit 10:19:09 INFO - -d DEST, --destination=DEST 10:19:09 INFO - Directory to install application into. [default: 10:19:09 INFO - "/builds/slave/test"] 10:19:09 INFO - --app=APP Application being installed. [default: firefox] 10:19:09 INFO - mkdir: /builds/slave/test/build/application 10:19:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:19:30 INFO - Reading from file tmpfile_stdout 10:19:30 INFO - Output received: 10:19:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:19:30 INFO - Running post-action listener: _resource_record_post_action 10:19:30 INFO - [mozharness: 2017-05-14 17:19:30.098409Z] Finished install step (success) 10:19:30 INFO - [mozharness: 2017-05-14 17:19:30.098586Z] Running run-tests step. 10:19:30 INFO - Running pre-action listener: _resource_record_pre_action 10:19:30 INFO - Running main action method: run_tests 10:19:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:30 INFO - 2017-05-14 10:19:30.112 screenresolution[1638:8686] starting screenresolution argv=screenresolution get 10:19:30 INFO - 2017-05-14 10:19:30.115 screenresolution[1638:8686] Display 0: 1600x1200x32@60 10:19:30 INFO - 2017-05-14 10:19:30.121 screenresolution[1639:8689] starting screenresolution argv=screenresolution list 10:19:30 INFO - Available Modes on Display 0 10:19:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:30 INFO - Intel Iris: 10:19:30 INFO - Chipset Model: Intel Iris 10:19:30 INFO - Type: GPU 10:19:30 INFO - Bus: Built-In 10:19:30 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:30 INFO - Vendor: Intel (0x8086) 10:19:30 INFO - Device ID: 0x0a2e 10:19:30 INFO - Revision ID: 0x0009 10:19:30 INFO - Displays: 10:19:30 INFO - Display: 10:19:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:30 INFO - Display Serial Number: 1600x1200 60 10:19:30 INFO - Main Display: Yes 10:19:30 INFO - Mirror: Off 10:19:30 INFO - Online: Yes 10:19:30 INFO - Rotation: Supported 10:19:30 INFO - Return code: 0 10:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:19:30 INFO - Python 2.7.3 10:19:30 INFO - Return code: 0 10:19:30 INFO - grabbing minidump binary from tooltool 10:19:30 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')]} 10:19:30 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 0x103622d30>, '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 0x1035d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103616be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:19:30 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 10:19:30 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 10:19:30 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 10:19:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:19:30 INFO - Return code: 0 10:19:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:19:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:19:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:19:30 INFO - ENV: RUST_BACKTRACE is now 1 10:19:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:19:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--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 10:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0337 --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 10:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ow6PxNaS1q/Render', 10:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:30 INFO - 'HOME': '/Users/cltbld', 10:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:30 INFO - 'LOGNAME': 'cltbld', 10:19:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:19:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:30 INFO - 'MOZ_NO_REMOTE': '1', 10:19:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:19:30 INFO - 'NO_EM_RESTART': '1', 10:19:30 INFO - 'PAGER': '/bin/cat', 10:19:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:30 INFO - 'PWD': '/builds/slave/test', 10:19:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:30 INFO - 'RUST_BACKTRACE': '1', 10:19:30 INFO - 'SHELL': '/bin/bash', 10:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QbgmNdNppj/Listeners', 10:19:30 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 10:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:30 INFO - 'USER': 'cltbld', 10:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:30 INFO - 'XPC_FLAGS': '0x0', 10:19:30 INFO - 'XPC_SERVICE_NAME': '0', 10:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--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 10:19:30 INFO - mozversion application_buildid: 20170514090816 10:19:30 INFO - mozversion application_changeset: 3a801856dea979b0c3f630e1b0ec58106cdcb380 10:19:30 INFO - mozversion application_display_name: Nightly 10:19:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:30 INFO - mozversion application_name: Firefox 10:19:30 INFO - mozversion application_remotingname: firefox 10:19:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:19:30 INFO - mozversion application_vendor: Mozilla 10:19:30 INFO - mozversion application_version: 55.0a1 10:19:30 INFO - mozversion platform_buildid: 20170514090816 10:19:30 INFO - mozversion platform_changeset: 3a801856dea979b0c3f630e1b0ec58106cdcb380 10:19:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:19:30 INFO - mozversion platform_version: 55.0a1 10:19:30 INFO - using testdate: 1494782370 10:19:30 INFO - actual date: 1494782370 10:19:30 INFO - starting webserver on 'localhost:49232' 10:19:30 INFO - SUITE-START | Running 2 tests 10:19:30 INFO - TEST-START | dromaeo_css 10:19:30 INFO - operating with platform_type : mac_ 10:19:30 INFO - Initialising browser for dromaeo_css test... 10:19:30 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpkQ6nc2/profile http://localhost:49232/getInfo.html) 10:19:33 INFO - PID 1649 | 2017-05-14 10:19:33.381 plugin-container[1651:8876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 10:19:33 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:33 INFO - PID 1649 | 2017-05-14 10:19:33.385 plugin-container[1651:8876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 10:19:33 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:33 INFO - PID 1649 | 2017-05-14 10:19:33.385 plugin-container[1651:8876] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:33 INFO - PID 1649 | __metrics Screen width/height:1600/1200 10:19:33 INFO - PID 1649 | colorDepth:24 10:19:33 INFO - PID 1649 | Browser inner width/height: 1024/691 10:19:33 INFO - PID 1649 | __metrics 10:19:34 INFO - TEST-INFO | 1649: exit 0 10:19:34 INFO - Browser initialized. 10:19:34 INFO - Running cycle 1/1 for dromaeo_css test... 10:19:34 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpkQ6nc2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:36 INFO - PID 1654 | 2017-05-14 10:19:36.720 plugin-container[1655:9047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 10:19:36 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:36 INFO - PID 1654 | 2017-05-14 10:19:36.721 plugin-container[1655:9047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990f, name = 'com.apple.CFPasteboardClient' 10:19:36 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:36 INFO - PID 1654 | 2017-05-14 10:19:36.721 plugin-container[1655:9047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:37 INFO - PID 1654 | 2017-05-14 10:19:37.194 plugin-container[1656:9093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 10:19:37 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:37 INFO - PID 1654 | 2017-05-14 10:19:37.195 plugin-container[1656:9093] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x951b, name = 'com.apple.CFPasteboardClient' 10:19:37 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:37 INFO - PID 1654 | 2017-05-14 10:19:37.195 plugin-container[1656:9093] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:46 INFO - PID 1654 | RSS: Main: 229150720 10:19:46 INFO - PID 1654 | 10:22:50 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49232/tests/dromaeo/cssquery-ext.html) 10:22:52 INFO - PID 1654 | RSS: Main: 252243968 10:22:52 INFO - PID 1654 | 10:25:54 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-ext.html (next: http://localhost:49232/tests/dromaeo/cssquery-jquery.html) 10:25:55 INFO - PID 1654 | RSS: Main: 261881856 10:25:55 INFO - PID 1654 | 10:28:57 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49232/tests/dromaeo/cssquery-mootools.html) 10:28:57 INFO - PID 1654 | RSS: Main: 271556608 10:28:57 INFO - PID 1654 | 10:31:59 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49232/tests/dromaeo/cssquery-prototype.html) 10:32:00 INFO - PID 1654 | RSS: Main: 264667136 10:32:00 INFO - PID 1654 | 10:35:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49232/tests/dromaeo/cssquery-yui.html) 10:35:03 INFO - PID 1654 | RSS: Main: 268042240 10:35:03 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-yui.html (next: http://localhost:49232/tests/dromaeo/cssquery-dojo.html) 10:38:05 INFO - PID 1654 | RSS: Main: 274735104 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | __start_tp_report 10:38:05 INFO - PID 1654 | _x_x_mozilla_page_load 10:38:05 INFO - PID 1654 | _x_x_mozilla_page_load_details 10:38:05 INFO - PID 1654 | |i|pagename|runs| 10:38:05 INFO - PID 1654 | |0;dojo.html;3550.7336949728774;3573.589037260714;3591.6947059499976;3619.9095022624433;3629.6007439181685;6113.755449782015;6230.252369715635;6290.150829637997;6304.527160462961;6312.905306420407;6231.532635052378;6261.843453913654;6269.874602507947;6276.623402595836;6284;5464.153056276284;5468.945310546884;5475.863103422406;5492.450754924515;5516.613837031409;5526.972365138189;5566.777328906859;5612.943870561283;5654.46282603152;5661.46216109471;5408.891822163535;5476.780928762844;5538.556915446755;5585.74864131113;5607.186957891104;4706.458757242926;5342.171963345688;5451;5482.150266708666;5497.5601951843755;4758.5241475852135;5223.3209682741;5429.809956651498;5472.452754724535;5535.58483113764;5116.002379535992;6187.041008643668;6266.373362663743;6266.811995640138;6272.40412160844;6405.103285540028;6724.66376681164;6760.3239676032;6767.526273160881;6803.065499235634;6677.766278180289;6743.629100399476;6838.384545390889;6853.451723862079;6863.622500762456;4732.692374995614;6306.653134077578;6328.905066424008;6352.904706429407;6371.808845734636;6957.373836354753;6992.895106573405;7026.718931242795;7038.331358520933;7079.575225486487;5472.644278121909;6393.392627700361;6539.280679125292;6602.867942641216;6634.270230274666;5538.224648549206;6770.830729231809;6770.966145169243;6795.286494918061;6803.353681400259;6494.253160886464;6634.469242460592;6695.966520167368;6713.093732346071;6726;6529.249136349285;6559.311272316433;6567.737290508326;6582.868342633121;6586.37429444202;5363.249145119687;5367.463253674679;5376.8118115865755;5379.811706590251;5386.5152136307925;4387.398599511219;4746.264329029006;4756.072565849626;4785.808567657325;4790.401199850019;6305.4009869062365;6337.588056776295;6377.298497165308;6408.391202835724;6457.903131453032;6874.450043996469;6879.5184337096925;6911.205211400753;6913.2049814272;6932.445404367639;6328.905066424008;6470.676466176672;6473.611583305017;6495.642739649365;6496.6426846522945;5851.736671849777;5935.1987481690285;5942.405759424023;6035.66803825785;6043.54673399488;6900.171979362509;6914.377706006484;6950.548214366252;7024.719011239493;7064.611446370499;6606.174228221473;6855.862882742217;6925.376716095576;6930.653467326714;6959;6880.965595171992;6958.617276049866;7034.155901291878;7054.153501579844;7086.39765619928;6740.831479213059;6762.951742479923;6766.898496522457;6772.288909664611;6778.288279730755;6485.929821579387;6677.198736151693;6694.397504224643;6753.898691519729;6871.759488417882;5939.762409503572;6586.1438013058;6597.80206593803;6622.635755033327;6669.533132680665;6598;6604.306547812603;6624.271330153583;6634.601923884582;6650.73397064112;5968.910466342922;6238.469730072995;6377.96811015942;6516.120323756232;6550.050242714875;6521.119648847345;6545.825274741651;6601.768938087336;6628.171478565179;6655.201375834931;6517.576357536745;6522.902156467562;6522.967385163044;6531.934680653323;6535.607863528204 10:38:05 INFO - PID 1654 | |1;ext.html;690.5235387582568;831.1522247307748;888.9066648001959;1143.8055530559805;1150.7640933608611;34732;37483.81258093709;37660.05849853755;37843.4323485148;37914.810425947835;37000;37289.627103728955;37319.62680373195;37392.43911341325;37401.81299093501;5913.497352725023;7686.116096648887;7783.104942931579;7790.45466817323;7803.29770320671;5987.084133077417;7012.439004879598;8143.104258531557;8289.253967142957;8296.83406331873;8291.751247462586;8369.83260334793;8406.159384061606;8409.579521023956;8415.074341822394;6401.039844023388;6847.972804079428;8287.834243315161;8322.875156872651;8383.664653413856;8001;8101.108878023413;8173.427860049799;8199.79500512486;8209.179082091803;35659;35731.821340893126;35755.28489430197;35826;35868.28263434717;3606.1886075632774;3606.783592984403;3635.781853088823;3636.5090712753968;3639.8180090995343;29873.551896721565;29974;30073;30162.793488260442;30215.395692086255;2827.476916770404;2840.9431811363656;3100.224943764059;3143.7170654640963;3144.5754823099046;285377;285840;286183;286926;287443;44913;47786.522134778905;48284.275735863994;48471.27293090607;48483.75758121187;49774.0045199094;49780.50219497831;49983.75008124936;50000;50025.74987125041;13919.164850109026;14055.648608784657;14062.71874562503;14062.718745625234;14067.467855785851;11001.339919604688;11011;11220.270682405617;11226.270292430965;11272.943635281936;11405.714857128472;13220.867791322156;13534.93232533831;13576.93211533936;13589.728205435837;5148.253503242009;6010.068439391901;6043.969780151071;6054.727537260834;6088.4215999479975;906.5920335848895;972.2756546372998;977.5112443778111;1014.9441780701984;1079.3523885668535;22019;24531.14141005056;24540.87729561341;24631.87684061568;24641.383965401008;958.5973890965811;1119.0823524709822;1123.662901129658;1161.8431511745805;1168.2581560709011;11050.944745276383;11660.901037813275;12368.257904525757;12635.43140558677;12653.30406827615;340171;341053;341165;341340;342427;53847.46152538503;54087.45912540903;54150;54179.45820541823;54243.45756542463;1503.5639664497392;1526.7404541227795;1553.3087775939825;1559.6412825050302;1562.6015366081576;24352.51294974055;24424.023039078238;24492.265232043068;24492.6326105105;24663.260102196964;31070.844645776626;38495.615043849204;38577.421338680506;38582.80708596551;38618.80690596641;272850;274151;274346;275263;275537;37184.6281537192;37447.43828842515;37489.81255093707;37507.81246093752;37565.87302380933;35623.465648015816;44872.77563612161;45474.863128422054;47095.293570595786;47345.526544734115;25889.482210356065;29990.70009299879;31654.525182122747;31668.683313166573;31725.5241171378;30703.846480767454;30811.459427028112;30838.845805770827;31710.36579268448;32005.839970799996;37950.43074353942;38029;38042;38131.618683812805;38160.23679526449;30024.234719214746;31587.684123159393;32001.039968800975;32080.67919320777;32364.514532282505;24902;25025.624615631143;25403.110891118722;25446.872765636053;25557.361065972756 10:38:05 INFO - PID 1654 | |2;jquery.html;428.57999160822374;440.79723327269454;442.3652059294912;457.73222664741127;458.19129236896885;19863.404097877068;21297.467563310907;21854.01656925438;22031.779682203174;22137.667934980967;22748.862556872173;22755.431114222152;22888.313350599467;23057.769422305773;24183.186261030405;5233.685978841263;5932.970335148319;6021.187139736135;6180.381961803829;6180.505559555225;5960.433758792908;6137.171481849938;6138.907916381258;6141.877162456748;6159.969200154015;5841.620294680833;6050.848728781772;6099.57302988791;6101.877962440749;6124.1732366130445;5994.760209591611;6001.729922153513;6010.459058684695;6010.969945150268;6058.485028772559;4600.861974140781;5257.842264732064;5301.966116607263;5379.65032272901;5391.892162156774;27500;27642.170734877986;27689.892404303802;27769.722302776914;28189.57715634267;2224.5773303072365;2277.6241920083166;2292.3810571145696;2293.0942277800177;2299.977000229993;17725.556861078447;17938.730919036225;18128.90935545327;18308.176132074088;18417.987010714405;2124.5857057873723;2141.5288636499945;2188.190369563256;2200.7799220078023;2202.1631779923687;123553;150318;151103;151812;171202.99999999875;10642.25504214713;10931.852155523715;12357;12914.806277905838;13101.475940962446;16560.50318490423;16809.747853782203;16870.662586748444;16875.409360672562;16876.38416045515;8916.331275154389;9183.448993060305;9189.954050229706;9214.585343659552;9290.349675522784;6960.234374218832;8467.40728149036;8642.395032347675;8721.345899057596;8919.777005574913;7983.5209887406945;8106.351491880753;8123.7156699515235;8152.103268640445;8158.592070396457;1995.9222020108978;2189.6168170570086;2208.3043841189974;2212.623853944833;2405.3625789165885;941.3034354577563;953.2326477185793;953.7410617984333;960.5149399553181;985.8028394321165;20732.274370397266;21345;21446.46383840385;21495.78504214938;21595.892020540112;972.2562239886493;981.8085473332775;1000.1548691355792;1010.1211945607369;1014.0467960117613;10309.96900309978;10647.307924985005;11051.944740276247;11069;11083.72290692739;535216;537171.3141434268;540833;542131;549166;99587;132232.33883830713;137690;139691;139702.1688574817;1093.305750848217;1132.8753837077916;1243.0614885761192;1275.101053757099;1276.0238417610535;18230.72653910219;19738.210471580976;20258.987050647702;20943.057562409424;21340.61286016404;42187.578124218366;54570.45429545654;56803.71598141983;57821.84356312766;58647.70676146592;78680;78936.60531697304;80014;80665;81107.18892810996;8680.131986801522;9040.095990400907;9184.632614695338;9213.216876565568;9313.25493960495;14192.787108193179;14243.287835607971;14261.073030253001;14339.426422942965;14641.780373294621;11682.24065435744;11869.169158158846;11915.702107447383;12012.699682508008;12039.217450865666;10673;11024.944875275572;11200.775984480428;11271.323720576791;11460.140489463156;11950.880491194977;12054.521442276615;12090.274583525017;12170.452329645364;12184.817227741769;8771.649134034822;8990.775230619025;8997.846711864215;9098;9293.303002274992;11764.117691173367;11962.880371196177;11994.820077699016;12068.939655301667;12161.209521381083 10:38:05 INFO - PID 1654 | |3;mootools.html;1390;1431.5132854829362;1453.2806260900857;1455.0105927968991;1458.4385011770469;11711.53153873845;11918.225315354495;12232.633021009373;12267.509299628015;12508.437120329585;10310.432926189056;12132.575359862407;12176;12363.50545978162;12386.938065309683;2541.3773625461786;2594.221733479958;2596.298999270196;2599;2608.2827222513865;2530.962035569468;2564.5512035393826;2583.173384516955;2583.8966441342322;2584.4572639745675;2542.2627438042946;2560.0783717861555;2597.766201041906;2599.090318388568;2601.609758536216;2522.445062086338;2546.363409147704;2562.3337932137592;2570.588705807062;2574.768270855613;2449.6448015037718;2544;2552.834065785718;2554.7572980566815;2559.1043134902634;11794;11882.108841836896;11895.881041189565;11918.821217681741;11921.344326062062;6624.072629831828;6862.9313706863295;6867.622280774555;6868;6872.965635171792;4073.531543872463;4402.7358358498595;4477.082198149372;4480.260756975095;4481.865544033684;1221.4686611324103;1235.8702336254653;1291.373683763374;1297.5264028629576;1301.954431594899;15040.849591504055;15639.843601563953;16530.421435249707;16593.170341482877;16615;5516.724163791794;5622.690752008679;5836.678982655994;5842.649441033466;5861.853453663693;11648.767024659459;11877.109216808602;11888.762224755457;11899.583514576949;11906.166568340306;5582.748776305076;6529;6616.735330586819;6634.966825165843;6641.435477984329;5243.4232234454175;5991.281046274475;6126.816195514141;6271.749130034838;6319.399657032666;3749.456328832304;4412.7352358857925;4494.505604383515;4500.9099818004115;4536.160810250114;1304.3543445994294;1323.543377534749;1324.1065276654285;1329.7008173161155;1335.5058628307588;796.6377494484497;798.7483942558192;816.8366326734676;822.1039067416544;849.227203245044;6269.46709529686;6301.936980630227;6572.418032172477;7159.785206443815;7543.7736867894055;944.9291303152291;973.7955029443755;974.5176137811828;979.7942432089341;980.8332583460829;4154.854580089683;4398.164348773723;4623.237165867594;4625.7687115644085;4641.559051890065;14693.632659183391;14809.851901481063;15260.23698815077;15350.692986140215;15393.384264629289;14633;14744.262786860614;15299.61750956235;15420.46028389001;15435;8661.30709543235;8728.869066964;8798.032216456184;8803.515806630698;8814.118588141135;7005.649717514002;7064.646767661494;7377.372923301365;7381.8154546136775;7407.296306850841;13537.93231033878;13572.321383930961;13747.793783093062;13808.30738006079;14005.22971236551;13823.447062117804;13923.930380348034;14028.509002184874;14099;14104.929475352557;9372.109649583279;9538.093881081286;9546.42721436716;9554.426734395958;9558.56986435598;8948.418352807046;11203.551857925591;11292.887071129513;11320.773584528428;11345.20583559176;9188.770280742767;9303.534823258675;9351.971283158982;9395.342326037386;9445;9066.773330666521;9141.99438061826;9196.954015229881;9242.72271831846;9264.95367523158;8039.959800200962;8262.628181731716;8290.129536398592;8383.161683831568;8394.24451799341;6031.0585041618715;6106.175666284995;6149.169862068564;6265.216847894013;6378.266499352634;6020.096985452217;6200.937990620217;6258.4680302173;6288.811335659938;6335.669509403077 10:38:05 INFO - PID 1654 | |4;prototype.html;354.24545717621464;388.5609261534466;402.3100382843423;413.40056917469667;414.81126087630133;6091.664958427285;6286.022515008337;6453.709583068761;6479.449246814021;6484.578502397341;5677.013045434096;5678.517326027282;5723.685197314146;6162.969185154079;6362.14111095002;4828.517148285178;4860.173770459013;4885.975570122162;4904.239842824368;4907.803687852499;4597.655175861807;4805.495422980589;4826.444958829727;4848.054629347279;4848.490908454597;4029.7380670256484;4543.750093744843;4803.879903002418;4819.108464933998;4859.951400486003;3815.2369526094612;4193.895152621179;4806.327114204014;4824.734639594821;4852.708837469745;3750.868719594801;3882.786446745428;3900.2979463696524;3928.2339943710986;3935.252302062599;4949.7030178189425;5051.419086805027;5859.7949071782705;6257;6266;5111.386633603991;5478.369987451414;5501.284832971728;5510.173473978935;5518.944810551883;5250.606204534675;6197.96901015492;6558.213014438298;6698.229703584053;6831.385175334244;4459.687821852472;4486.394336764528;4487.439070116236;4496.089162106125;4498.797554110073;6299.622022678654;6589.835254118685;6643.169603799525;7177.210506844243;7229.879368697861;5101.795928162906;5152.583039664048;5153.2270159476375;5163.483651634881;5185.688858668492;4521.8191272349395;5130.486951304914;5251.264822924793;5294.338207724035;5298.966701493172;4673.579377856009;5598.776048957996;5727.140928860621;5824.689444874954;5909.143174239797;6796.388325050671;6808.761693340709;6840.555363901331;6885.552439091443;6913.41235994936;1578.9921050394905;1701.4091824144464;1765.938192163268;1790.6687262856153;1806.2142967809045;991.6578780320777;1239.0954603139758;1242.8384310039637;1244.539520377466;1245.906557008228;5673.716314184274;5697.888911662269;6112.572119951649;6523.314327405001;6528.151340325728;6338.58799178052;6879.213804136733;7007.649617519103;7025.473089518307;7061.399781018568;5899.274826697569;6408.807735767934;6453.290138084807;6518.413342799171;6562.376574225441;2879.4097210071886;3569.8750543730844;3662.7069834413182;3675.320065787838;3675.8162091895297;5779.826605201851;6086;6924.58452492852;7193.568385896863;7254.637268136677;5922.644641321534;6252.874942501216;7197.4961752677855;7206.87397217822;7224;5629.802956896571;6507.446867016357;6976.19773726028;7195;7280.817979550554;6134.325224225242;6309.589876658002;7040.331168538981;7054.858902822018;7071.82320441993;6268.780592679234;6792.573559552549;6931.930680693128;6938.895916561357;6946.2011868635755;6621.172353455818;6824.146981627297;7224.74713385029;7225.313595208447;7302.853942921006;4011.338129208648;4435;4705.576498115186;5000.949800541782;5101.540861322499;6081.969590152021;6252.781152659635;6315.873682526416;6983.546069505466;7010.859782804418;5807.361190268983;6205.5345849060595;6551.672416379257;6700.899486507608;6793.422559082335;6647.302033286629;6700.296468870698;6705.228898676714;6722.831929201809;6750.8649827004165;6570.441512471493;6571.835704107244;6577.566300468857;6585.670716464253;6591.967040164768;5714.971425142848;6386.744530218739;6723.899141512979;6734.73061077571;6794.810908090965;4985.950140498694;5803.564732645153;5958;6597.50518711089;6643.368879956396 10:38:05 INFO - PID 1654 | |5;yui.html;731.239510908655;818.9795255118621;821.2444551013067;863.3869952333845;870.6865528409771;16306.266218020188;16925.32298708052;16944.23750931208;18212.998285094316;19312.227510899582;17725.55686107848;18077.457676269732;18191.27234910602;18402.539936501595;18526.907365463187;967.5500892085162;1022.7136401807505;1023.2376879224951;1024.861643678105;1026.928115031948;904.3579058868179;1005.4419797012655;1020.7907378987289;1021.8416145497459;1022.560299071399;1065.8507808906759;1085.4626959654981;1085.6688709943464;1086.684861390196;1089.1559041742673;1059.5549869054978;1077.4181941751444;1077.8383242513607;1079.1528650009743;1079.5196137718717;796.8764841449585;799.3285640062376;801.5471258738852;802.1016461563087;804.031142473324;15218.695626087527;16913.985160890385;16970.981741095573;17008.06455644939;17075.743863842054;16794.580135496588;17275.654486910316;17308;17556.561085972826;17577.384791532237;14163.78754318686;14269.64325891861;14303;14421.639459013504;14585.416583336653;3560.8753693620597;3604.9725828138853;3612.8735494257576;3618.2220822523022;3635.8727444539577;94516;122363.3881830594;123736.76263237433;125329;126612;13186.802197967038;13332.200067995951;13390.93304533471;13473.393697283645;13542.458301667823;23402.531949360917;24921.62617560738;26251.73748262531;26664.73335266661;26832.060877869182;6404.679766011681;6719.899201511981;7783.532988020737;7783.688652453839;7790.493617914817;3174.730147937405;3452.4476083826466;3856.2094770571966;3978.4827972363987;3979.8806035818393;4328.220920234388;4390.100029493946;4395.8021889014435;4422.248217802981;4426.1369033038245;871.5032431513977;895.4671970177864;896.1307531694246;896.7713233125511;897.3718397122041;651.2478087808589;656.9540132190797;668.4117976180929;676.3236763236763;687.7077242172057;9802.264830137769;11278.323300601991;11771.705707357214;11823;12134.211276267015;655.983600409984;658.7845425190516;659.6932426421737;662.5891946992799;687.1376422589618;5020.723860187652;5049.84850454487;5088.92366614501;5093;5269.604779641542;111059.44470277597;112948;113857;114195;114241.85758142479;81375;83352.91500222428;89377.21245575183;91251.54374228219;97917;79138;81073.89261073846;83188.58405707932;84218;84587.15412845917;10741.409222492837;11972.700682483008;12485.188462749893;12584.244945303311;12586.118971672262;40678.77963661095;40742;40780;40998.59001410067;41332.58667413287;51938.74030629823;52622.736886315324;52631;52862.73568632132;53393;16039;16086.597835054217;16404.425845095364;16561.00633961966;16585.087820170007;16481.17594120265;16733.414330498374;17168.656626867643;17187.742183867504;17327.82672173262;16408.9210463544;16470.917645412177;16612.169391530617;16865.494035178963;16876.24056917466;15845.52463426099;16094.395046046475;16237.269322880262;16350.509484715478;16426.671466570842;12655;15937.920310398375;16038.759418608497;16065.839341606434;16281.67436651284;11529.538818447503;11619.76760464769;11964.227287599562;12071.517139314681;12086.274823510617;12687.873121268669;13827.654308642364;14129.717405652034;14132.717345653235;14198.858011419754 10:38:05 INFO - PID 1654 | __end_tp_report 10:38:05 INFO - PID 1654 | __start_cc_report 10:38:05 INFO - PID 1654 | _x_x_mozilla_cycle_collect,410 10:38:05 INFO - PID 1654 | __end_cc_report 10:38:05 INFO - PID 1654 | __startTimestamp1494783485615__endTimestamp 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | ------- Summary: start ------- 10:38:05 INFO - PID 1654 | Number of tests: 6 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6197.98 Median:6494.25 stddev:769.64 (11.9%) stddev-sans-first:743.46 10:38:05 INFO - PID 1654 | Values: 3550.7 3573.6 3591.7 3619.9 3629.6 6113.8 6230.3 6290.2 6304.5 6312.9 6231.5 6261.8 6269.9 6276.6 6284.0 5464.2 5468.9 5475.9 5492.5 5516.6 5527.0 5566.8 5612.9 5654.5 5661.5 5408.9 5476.8 5538.6 5585.7 5607.2 4706.5 5342.2 5451.0 5482.2 5497.6 4758.5 5223.3 5429.8 5472.5 5535.6 5116.0 6187.0 6266.4 6266.8 6272.4 6405.1 6724.7 6760.3 6767.5 6803.1 6677.8 6743.6 6838.4 6853.5 6863.6 4732.7 6306.7 6328.9 6352.9 6371.8 6957.4 6992.9 7026.7 7038.3 7079.6 5472.6 6393.4 6539.3 6602.9 6634.3 5538.2 6770.8 6771.0 6795.3 6803.4 6494.3 6634.5 6696.0 6713.1 6726.0 6529.2 6559.3 6567.7 6582.9 6586.4 5363.2 5367.5 5376.8 5379.8 5386.5 4387.4 4746.3 4756.1 4785.8 4790.4 6305.4 6337.6 6377.3 6408.4 6457.9 6874.5 6879.5 6911.2 6913.2 6932.4 6328.9 6470.7 6473.6 6495.6 6496.6 5851.7 5935.2 5942.4 6035.7 6043.5 6900.2 6914.4 6950.5 7024.7 7064.6 6606.2 6855.9 6925.4 6930.7 6959.0 6881.0 6958.6 7034.2 7054.2 7086.4 6740.8 6763.0 6766.9 6772.3 6778.3 6485.9 6677.2 6694.4 6753.9 6871.8 5939.8 6586.1 6597.8 6622.6 6669.5 6598.0 6604.3 6624.3 6634.6 6650.7 5968.9 6238.5 6378.0 6516.1 6550.1 6521.1 6545.8 6601.8 6628.2 6655.2 6517.6 6522.9 6523.0 6531.9 6535.6 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:45106.94 Median:35641.23 stddev:79287.33 (222.5%) stddev-sans-first:79439.61 10:38:05 INFO - PID 1654 | Values: 690.5 831.2 888.9 1143.8 1150.8 34732.0 37483.8 37660.1 37843.4 37914.8 37000.0 37289.6 37319.6 37392.4 37401.8 5913.5 7686.1 7783.1 7790.5 7803.3 5987.1 7012.4 8143.1 8289.3 8296.8 8291.8 8369.8 8406.2 8409.6 8415.1 6401.0 6848.0 8287.8 8322.9 8383.7 8001.0 8101.1 8173.4 8199.8 8209.2 35659.0 35731.8 35755.3 35826.0 35868.3 3606.2 3606.8 3635.8 3636.5 3639.8 29873.6 29974.0 30073.0 30162.8 30215.4 2827.5 2840.9 3100.2 3143.7 3144.6 285377.0 285840.0 286183.0 286926.0 287443.0 44913.0 47786.5 48284.3 48471.3 48483.8 49774.0 49780.5 49983.8 50000.0 50025.7 13919.2 14055.6 14062.7 14062.7 14067.5 11001.3 11011.0 11220.3 11226.3 11272.9 11405.7 13220.9 13534.9 13576.9 13589.7 5148.3 6010.1 6044.0 6054.7 6088.4 906.6 972.3 977.5 1014.9 1079.4 22019.0 24531.1 24540.9 24631.9 24641.4 958.6 1119.1 1123.7 1161.8 1168.3 11050.9 11660.9 12368.3 12635.4 12653.3 340171.0 341053.0 341165.0 341340.0 342427.0 53847.5 54087.5 54150.0 54179.5 54243.5 1503.6 1526.7 1553.3 1559.6 1562.6 24352.5 24424.0 24492.3 24492.6 24663.3 31070.8 38495.6 38577.4 38582.8 38618.8 272850.0 274151.0 274346.0 275263.0 275537.0 37184.6 37447.4 37489.8 37507.8 37565.9 35623.5 44872.8 45474.9 47095.3 47345.5 25889.5 29990.7 31654.5 31668.7 31725.5 30703.8 30811.5 30838.8 31710.4 32005.8 37950.4 38029.0 38042.0 38131.6 38160.2 30024.2 31587.7 32001.0 32080.7 32364.5 24902.0 25025.6 25403.1 25446.9 25557.4 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:35286.86 Median:12590.35 stddev:91790.26 (729.1%) stddev-sans-first:92010.44 10:38:05 INFO - PID 1654 | Values: 428.6 440.8 442.4 457.7 458.2 19863.4 21297.5 21854.0 22031.8 22137.7 22748.9 22755.4 22888.3 23057.8 24183.2 5233.7 5933.0 6021.2 6180.4 6180.5 5960.4 6137.2 6138.9 6141.9 6160.0 5841.6 6050.8 6099.6 6101.9 6124.2 5994.8 6001.7 6010.5 6011.0 6058.5 4600.9 5257.8 5302.0 5379.7 5391.9 27500.0 27642.2 27689.9 27769.7 28189.6 2224.6 2277.6 2292.4 2293.1 2300.0 17725.6 17938.7 18128.9 18308.2 18418.0 2124.6 2141.5 2188.2 2200.8 2202.2 123553.0 150318.0 151103.0 151812.0 171203.0 10642.3 10931.9 12357.0 12914.8 13101.5 16560.5 16809.7 16870.7 16875.4 16876.4 8916.3 9183.4 9190.0 9214.6 9290.3 6960.2 8467.4 8642.4 8721.3 8919.8 7983.5 8106.4 8123.7 8152.1 8158.6 1995.9 2189.6 2208.3 2212.6 2405.4 941.3 953.2 953.7 960.5 985.8 20732.3 21345.0 21446.5 21495.8 21595.9 972.3 981.8 1000.2 1010.1 1014.0 10310.0 10647.3 11051.9 11069.0 11083.7 535216.0 537171.3 540833.0 542131.0 549166.0 99587.0 132232.3 137690.0 139691.0 139702.2 1093.3 1132.9 1243.1 1275.1 1276.0 18230.7 19738.2 20259.0 20943.1 21340.6 42187.6 54570.5 56803.7 57821.8 58647.7 78680.0 78936.6 80014.0 80665.0 81107.2 8680.1 9040.1 9184.6 9213.2 9313.3 14192.8 14243.3 14261.1 14339.4 14641.8 11682.2 11869.2 11915.7 12012.7 12039.2 10673.0 11024.9 11200.8 11271.3 11460.1 11950.9 12054.5 12090.3 12170.5 12184.8 8771.6 8990.8 8997.8 9098.0 9293.3 11764.1 11962.9 11994.8 12068.9 12161.2 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:7246.25 Median:4114.19 stddev:4533.76 (110.2%) stddev-sans-first:4525.12 10:38:05 INFO - PID 1654 | Values: 1390.0 1431.5 1453.3 1455.0 1458.4 11711.5 11918.2 12232.6 12267.5 12508.4 10310.4 12132.6 12176.0 12363.5 12386.9 2541.4 2594.2 2596.3 2599.0 2608.3 2531.0 2564.6 2583.2 2583.9 2584.5 2542.3 2560.1 2597.8 2599.1 2601.6 2522.4 2546.4 2562.3 2570.6 2574.8 2449.6 2544.0 2552.8 2554.8 2559.1 11794.0 11882.1 11895.9 11918.8 11921.3 6624.1 6862.9 6867.6 6868.0 6873.0 4073.5 4402.7 4477.1 4480.3 4481.9 1221.5 1235.9 1291.4 1297.5 1302.0 15040.8 15639.8 16530.4 16593.2 16615.0 5516.7 5622.7 5836.7 5842.6 5861.9 11648.8 11877.1 11888.8 11899.6 11906.2 5582.7 6529.0 6616.7 6635.0 6641.4 5243.4 5991.3 6126.8 6271.7 6319.4 3749.5 4412.7 4494.5 4500.9 4536.2 1304.4 1323.5 1324.1 1329.7 1335.5 796.6 798.7 816.8 822.1 849.2 6269.5 6301.9 6572.4 7159.8 7543.8 944.9 973.8 974.5 979.8 980.8 4154.9 4398.2 4623.2 4625.8 4641.6 14693.6 14809.9 15260.2 15350.7 15393.4 14633.0 14744.3 15299.6 15420.5 15435.0 8661.3 8728.9 8798.0 8803.5 8814.1 7005.6 7064.6 7377.4 7381.8 7407.3 13537.9 13572.3 13747.8 13808.3 14005.2 13823.4 13923.9 14028.5 14099.0 14104.9 9372.1 9538.1 9546.4 9554.4 9558.6 8948.4 11203.6 11292.9 11320.8 11345.2 9188.8 9303.5 9352.0 9395.3 9445.0 9066.8 9142.0 9197.0 9242.7 9265.0 8040.0 8262.6 8290.1 8383.2 8394.2 6031.1 6106.2 6149.2 6265.2 6378.3 6020.1 6200.9 6258.5 6288.8 6335.7 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5455.99 Median:5940.32 stddev:1654.45 (27.9%) stddev-sans-first:1614.17 10:38:05 INFO - PID 1654 | Values: 354.2 388.6 402.3 413.4 414.8 6091.7 6286.0 6453.7 6479.4 6484.6 5677.0 5678.5 5723.7 6163.0 6362.1 4828.5 4860.2 4886.0 4904.2 4907.8 4597.7 4805.5 4826.4 4848.1 4848.5 4029.7 4543.8 4803.9 4819.1 4860.0 3815.2 4193.9 4806.3 4824.7 4852.7 3750.9 3882.8 3900.3 3928.2 3935.3 4949.7 5051.4 5859.8 6257.0 6266.0 5111.4 5478.4 5501.3 5510.2 5518.9 5250.6 6198.0 6558.2 6698.2 6831.4 4459.7 4486.4 4487.4 4496.1 4498.8 6299.6 6589.8 6643.2 7177.2 7229.9 5101.8 5152.6 5153.2 5163.5 5185.7 4521.8 5130.5 5251.3 5294.3 5299.0 4673.6 5598.8 5727.1 5824.7 5909.1 6796.4 6808.8 6840.6 6885.6 6913.4 1579.0 1701.4 1765.9 1790.7 1806.2 991.7 1239.1 1242.8 1244.5 1245.9 5673.7 5697.9 6112.6 6523.3 6528.2 6338.6 6879.2 7007.6 7025.5 7061.4 5899.3 6408.8 6453.3 6518.4 6562.4 2879.4 3569.9 3662.7 3675.3 3675.8 5779.8 6086.0 6924.6 7193.6 7254.6 5922.6 6252.9 7197.5 7206.9 7224.0 5629.8 6507.4 6976.2 7195.0 7280.8 6134.3 6309.6 7040.3 7054.9 7071.8 6268.8 6792.6 6931.9 6938.9 6946.2 6621.2 6824.1 7224.7 7225.3 7302.9 4011.3 4435.0 4705.6 5000.9 5101.5 6082.0 6252.8 6315.9 6983.5 7010.9 5807.4 6205.5 6551.7 6700.9 6793.4 6647.3 6700.3 6705.2 6722.8 6750.9 6570.4 6571.8 6577.6 6585.7 6592.0 5715.0 6386.7 6723.9 6734.7 6794.8 4986.0 5803.6 5958.0 6597.5 6643.4 10:38:05 INFO - PID 1654 | 10:38:05 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:21728.07 Median:17317.91 stddev:30591.23 (176.6%) stddev-sans-first:30636.42 10:38:05 INFO - PID 1654 | Values: 731.2 819.0 821.2 863.4 870.7 16306.3 16925.3 16944.2 18213.0 19312.2 17725.6 18077.5 18191.3 18402.5 18526.9 967.6 1022.7 1023.2 1024.9 1026.9 904.4 1005.4 1020.8 1021.8 1022.6 1065.9 1085.5 1085.7 1086.7 1089.2 1059.6 1077.4 1077.8 1079.2 1079.5 796.9 799.3 801.5 802.1 804.0 15218.7 16914.0 16971.0 17008.1 17075.7 16794.6 17275.7 17308.0 17556.6 17577.4 14163.8 14269.6 14303.0 14421.6 14585.4 3560.9 3605.0 3612.9 3618.2 3635.9 94516.0 122363.4 123736.8 125329.0 126612.0 13186.8 13332.2 13390.9 13473.4 13542.5 23402.5 24921.6 26251.7 26664.7 26832.1 6404.7 6719.9 7783.5 7783.7 7790.5 3174.7 3452.4 3856.2 3978.5 3979.9 4328.2 4390.1 4395.8 4422.2 4426.1 871.5 895.5 896.1 896.8 897.4 651.2 657.0 668.4 676.3 687.7 9802.3 11278.3 11771.7 11823.0 12134.2 656.0 658.8 659.7 662.6 687.1 5020.7 5049.8 5088.9 5093.0 5269.6 111059.4 112948.0 113857.0 114195.0 114241.9 81375.0 83352.9 89377.2 91251.5 97917.0 79138.0 81073.9 83188.6 84218.0 84587.2 10741.4 11972.7 12485.2 12584.2 12586.1 40678.8 40742.0 40780.0 40998.6 41332.6 51938.7 52622.7 52631.0 52862.7 53393.0 16039.0 16086.6 16404.4 16561.0 16585.1 16481.2 16733.4 17168.7 17187.7 17327.8 16408.9 16470.9 16612.2 16865.5 16876.2 15845.5 16094.4 16237.3 16350.5 16426.7 12655.0 15937.9 16038.8 16065.8 16281.7 11529.5 11619.8 11964.2 12071.5 12086.3 12687.9 13827.7 14129.7 14132.7 14198.9 10:38:05 INFO - PID 1654 | -------- Summary: end -------- 10:38:05 INFO - PID 1654 | 10:38:06 INFO - TEST-INFO | 1654: exit 0 10:38:06 INFO - TEST-OK | dromaeo_css | took 1115615ms 10:38:06 INFO - TEST-START | kraken 10:38:06 INFO - operating with platform_type : mac_ 10:38:06 INFO - Initialising browser for kraken test... 10:38:06 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpZ2ZAsp/profile http://localhost:49232/getInfo.html) 10:38:08 INFO - PID 1665 | 2017-05-14 10:38:08.458 plugin-container[1666:11264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 10:38:08 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:08 INFO - PID 1665 | 2017-05-14 10:38:08.461 plugin-container[1666:11264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 10:38:08 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:08 INFO - PID 1665 | 2017-05-14 10:38:08.462 plugin-container[1666:11264] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:38:08 INFO - PID 1665 | __metrics Screen width/height:1600/1200 10:38:08 INFO - PID 1665 | colorDepth:24 10:38:08 INFO - PID 1665 | Browser inner width/height: 1024/691 10:38:08 INFO - PID 1665 | __metrics 10:38:09 INFO - TEST-INFO | 1665: exit 0 10:38:09 INFO - Browser initialized. 10:38:09 INFO - Running cycle 1/1 for kraken test... 10:38:09 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpZ2ZAsp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:11 INFO - PID 1667 | 2017-05-14 10:38:11.990 plugin-container[1668:11380] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 10:38:11 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:11 INFO - PID 1667 | 2017-05-14 10:38:11.991 plugin-container[1668:11380] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.CFPasteboardClient' 10:38:11 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:11 INFO - PID 1667 | 2017-05-14 10:38:11.991 plugin-container[1668:11380] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:38:12 INFO - PID 1667 | 2017-05-14 10:38:12.517 plugin-container[1669:11424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 10:38:12 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:12 INFO - PID 1667 | 2017-05-14 10:38:12.518 plugin-container[1669:11424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 10:38:12 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:12 INFO - PID 1667 | 2017-05-14 10:38:12.518 plugin-container[1669:11424] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:38:21 INFO - PID 1667 | RSS: Main: 245477376 10:38:21 INFO - PID 1667 | 10:38:24 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection) 10:38:25 INFO - PID 1667 | RSS: Main: 255860736 10:38:25 INFO - PID 1667 | 10:38:28 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-dft) 10:38:28 INFO - PID 1667 | RSS: Main: 263258112 10:38:28 INFO - PID 1667 | 10:38:32 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-fft) 10:38:33 INFO - PID 1667 | RSS: Main: 258248704 10:38:33 INFO - PID 1667 | 10:38:35 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator) 10:38:35 INFO - PID 1667 | RSS: Main: 260435968 10:38:35 INFO - PID 1667 | 10:38:37 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:38:38 INFO - PID 1667 | RSS: Main: 260919296 10:38:38 INFO - PID 1667 | 10:38:41 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom) 10:38:42 INFO - PID 1667 | RSS: Main: 266010624 10:38:42 INFO - PID 1667 | 10:38:45 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:45 INFO - PID 1667 | RSS: Main: 267657216 10:38:45 INFO - PID 1667 | 10:38:48 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial) 10:38:49 INFO - PID 1667 | RSS: Main: 267550720 10:38:49 INFO - PID 1667 | 10:38:51 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:51 INFO - PID 1667 | RSS: Main: 267055104 10:38:51 INFO - PID 1667 | 10:38:53 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:54 INFO - PID 1667 | RSS: Main: 267493376 10:38:54 INFO - PID 1667 | 10:38:56 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:56 INFO - PID 1667 | RSS: Main: 267378688 10:38:56 INFO - PID 1667 | 10:38:59 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:39:00 INFO - PID 1667 | RSS: Main: 267595776 10:39:00 INFO - PID 1667 | 10:39:03 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:39:04 INFO - PID 1667 | RSS: Main: 265371648 10:39:04 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49232/tests/kraken/driver.html?testName=ai-astar) 10:39:06 INFO - PID 1667 | RSS: Main: 267100160 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | __start_tp_report 10:39:06 INFO - PID 1667 | _x_x_mozilla_page_load 10:39:06 INFO - PID 1667 | _x_x_mozilla_page_load_details 10:39:06 INFO - PID 1667 | |i|pagename|runs| 10:39:06 INFO - PID 1667 | |0;ai-astar;103;113;114;113;111;114;113;121;164;152 10:39:06 INFO - PID 1667 | |1;audio-beat-detection;147;109;108;149;92;127;145;149;144;111 10:39:06 INFO - PID 1667 | |2;audio-dft;186;279;267;235;269;196;273;279;208;195 10:39:06 INFO - PID 1667 | |3;audio-fft;83;77;57;93;51;65;58;51;94;48 10:39:06 INFO - PID 1667 | |4;audio-oscillator;96;71;82;72;70;70;93;71;91;95 10:39:06 INFO - PID 1667 | |5;imaging-gaussian-blur;90;89;102;89;90;86;137;81;137;136 10:39:06 INFO - PID 1667 | |6;imaging-darkroom;97;97;98;98;97;96;120;136;154;97 10:39:06 INFO - PID 1667 | |7;imaging-desaturate;92;96;91;141;146;89;91;91;91;90 10:39:06 INFO - PID 1667 | |8;json-parse-financial;51;51;50;52;50;50;53;54;66;47 10:39:06 INFO - PID 1667 | |9;json-stringify-tinderbox;59;58;63;59;59;61;56;55;58;59 10:39:06 INFO - PID 1667 | |10;stanford-crypto-aes;81;85;85;80;85;87;83;103;110;109 10:39:06 INFO - PID 1667 | |11;stanford-crypto-ccm;140;137;141;145;157;141;149;149;223;144 10:39:06 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;223;238;242;183;189;185;186;214;234;195 10:39:06 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;66;73;80;108;78;103;103;76;71;70 10:39:06 INFO - PID 1667 | __end_tp_report 10:39:06 INFO - PID 1667 | __start_cc_report 10:39:06 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1025 10:39:06 INFO - PID 1667 | __end_cc_report 10:39:06 INFO - PID 1667 | __startTimestamp1494783546762__endTimestamp 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | ------- Summary: start ------- 10:39:06 INFO - PID 1667 | Number of tests: 14 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:121.80 Median:114.00 stddev:19.77 (17.3%) stddev-sans-first:19.76 10:39:06 INFO - PID 1667 | Values: 103.0 113.0 114.0 113.0 111.0 114.0 113.0 121.0 164.0 152.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:146.00 stddev:21.43 (14.7%) stddev-sans-first:21.62 10:39:06 INFO - PID 1667 | Values: 147.0 109.0 108.0 149.0 92.0 127.0 145.0 149.0 144.0 111.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:238.70 Median:268.00 stddev:38.89 (14.5%) stddev-sans-first:36.27 10:39:06 INFO - PID 1667 | Values: 186.0 279.0 267.0 235.0 269.0 196.0 273.0 279.0 208.0 195.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:67.70 Median:71.00 stddev:17.68 (24.9%) stddev-sans-first:17.87 10:39:06 INFO - PID 1667 | Values: 83.0 77.0 57.0 93.0 51.0 65.0 58.0 51.0 94.0 48.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:86.50 stddev:11.49 (13.3%) stddev-sans-first:10.85 10:39:06 INFO - PID 1667 | Values: 96.0 71.0 82.0 72.0 70.0 70.0 93.0 71.0 91.0 95.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:103.70 Median:87.50 stddev:23.33 (26.7%) stddev-sans-first:24.22 10:39:06 INFO - PID 1667 | Values: 90.0 89.0 102.0 89.0 90.0 86.0 137.0 81.0 137.0 136.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:97.00 stddev:20.71 (21.4%) stddev-sans-first:21.51 10:39:06 INFO - PID 1667 | Values: 97.0 97.0 98.0 98.0 97.0 96.0 120.0 136.0 154.0 97.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:101.80 Median:91.00 stddev:22.08 (24.3%) stddev-sans-first:23.14 10:39:06 INFO - PID 1667 | Values: 92.0 96.0 91.0 141.0 146.0 89.0 91.0 91.0 91.0 90.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:51.50 stddev:5.15 (10.0%) stddev-sans-first:5.43 10:39:06 INFO - PID 1667 | Values: 51.0 51.0 50.0 52.0 50.0 50.0 53.0 54.0 66.0 47.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:2.26 (3.8%) stddev-sans-first:2.40 10:39:06 INFO - PID 1667 | Values: 59.0 58.0 63.0 59.0 59.0 61.0 56.0 55.0 58.0 59.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:84.00 stddev:11.73 (14.0%) stddev-sans-first:11.89 10:39:06 INFO - PID 1667 | Values: 81.0 85.0 85.0 80.0 85.0 87.0 83.0 103.0 110.0 109.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:147.00 stddev:25.40 (17.3%) stddev-sans-first:26.52 10:39:06 INFO - PID 1667 | Values: 140.0 137.0 141.0 145.0 157.0 141.0 149.0 149.0 223.0 144.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.90 Median:218.50 stddev:23.93 (11.0%) stddev-sans-first:24.83 10:39:06 INFO - PID 1667 | Values: 223.0 238.0 242.0 183.0 189.0 185.0 186.0 214.0 234.0 195.0 10:39:06 INFO - PID 1667 | 10:39:06 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.80 Median:72.00 stddev:15.67 (21.8%) stddev-sans-first:15.39 10:39:06 INFO - PID 1667 | Values: 66.0 73.0 80.0 108.0 78.0 103.0 103.0 76.0 71.0 70.0 10:39:06 INFO - PID 1667 | -------- Summary: end -------- 10:39:06 INFO - PID 1667 | 10:39:07 INFO - TEST-INFO | 1667: exit 0 10:39:07 INFO - TEST-OK | kraken | took 61400ms 10:39:07 INFO - SUITE-END | took 1177s 10:39:07 INFO - Completed test suite (00:19:37) 10:39:07 INFO - javascript benchmark 10:39:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7963.079395876178, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3550.7336949728774, 3573.589037260714, 3591.6947059499976, 3619.9095022624433, 3629.6007439181685, 6113.755449782015, 6230.252369715635, 6290.150829637997, 6304.527160462961, 6312.905306420407, 6231.532635052378, 6261.843453913654, 6269.874602507947, 6276.623402595836, 6284.0, 5464.153056276284, 5468.945310546884, 5475.863103422406, 5492.450754924515, 5516.613837031409, 5526.972365138189, 5566.777328906859, 5612.943870561283, 5654.46282603152, 5661.46216109471, 5408.891822163535, 5476.780928762844, 5538.556915446755, 5585.74864131113, 5607.186957891104, 4706.458757242926, 5342.171963345688, 5451.0, 5482.150266708666, 5497.5601951843755, 4758.5241475852135, 5223.3209682741, 5429.809956651498, 5472.452754724535, 5535.58483113764, 5116.002379535992, 6187.041008643668, 6266.373362663743, 6266.811995640138, 6272.40412160844, 6405.103285540028, 6724.66376681164, 6760.3239676032, 6767.526273160881, 6803.065499235634, 6677.766278180289, 6743.629100399476, 6838.384545390889, 6853.451723862079, 6863.622500762456, 4732.692374995614, 6306.653134077578, 6328.905066424008, 6352.904706429407, 6371.808845734636, 6957.373836354753, 6992.895106573405, 7026.718931242795, 7038.331358520933, 7079.575225486487, 5472.644278121909, 6393.392627700361, 6539.280679125292, 6602.867942641216, 6634.270230274666, 5538.224648549206, 6770.830729231809, 6770.966145169243, 6795.286494918061, 6803.353681400259, 6494.253160886464, 6634.469242460592, 6695.966520167368, 6713.093732346071, 6726.0, 6529.249136349285, 6559.311272316433, 6567.737290508326, 6582.868342633121, 6586.37429444202, 5363.249145119687, 5367.463253674679, 5376.8118115865755, 5379.811706590251, 5386.5152136307925, 4387.398599511219, 4746.264329029006, 4756.072565849626, 4785.808567657325, 4790.401199850019, 6305.4009869062365, 6337.588056776295, 6377.298497165308, 6408.391202835724, 6457.903131453032, 6874.450043996469, 6879.5184337096925, 6911.205211400753, 6913.2049814272, 6932.445404367639, 6328.905066424008, 6470.676466176672, 6473.611583305017, 6495.642739649365, 6496.6426846522945, 5851.736671849777, 5935.1987481690285, 5942.405759424023, 6035.66803825785, 6043.54673399488, 6900.171979362509, 6914.377706006484, 6950.548214366252, 7024.719011239493, 7064.611446370499, 6606.174228221473, 6855.862882742217, 6925.376716095576, 6930.653467326714, 6959.0, 6880.965595171992, 6958.617276049866, 7034.155901291878, 7054.153501579844, 7086.39765619928, 6740.831479213059, 6762.951742479923, 6766.898496522457, 6772.288909664611, 6778.288279730755, 6485.929821579387, 6677.198736151693, 6694.397504224643, 6753.898691519729, 6871.759488417882, 5939.762409503572, 6586.1438013058, 6597.80206593803, 6622.635755033327, 6669.533132680665, 6598.0, 6604.306547812603, 6624.271330153583, 6634.601923884582, 6650.73397064112, 5968.910466342922, 6238.469730072995, 6377.96811015942, 6516.120323756232, 6550.050242714875, 6521.119648847345, 6545.825274741651, 6601.768938087336, 6628.171478565179, 6655.201375834931, 6517.576357536745, 6522.902156467562, 6522.967385163044, 6531.934680653323, 6535.607863528204], "value": 6146.268100049475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.5235387582568, 831.1522247307748, 888.9066648001959, 1143.8055530559805, 1150.7640933608611, 34732.0, 37483.81258093709, 37660.05849853755, 37843.4323485148, 37914.810425947835, 37000.0, 37289.627103728955, 37319.62680373195, 37392.43911341325, 37401.81299093501, 5913.497352725023, 7686.116096648887, 7783.104942931579, 7790.45466817323, 7803.29770320671, 5987.084133077417, 7012.439004879598, 8143.104258531557, 8289.253967142957, 8296.83406331873, 8291.751247462586, 8369.83260334793, 8406.159384061606, 8409.579521023956, 8415.074341822394, 6401.039844023388, 6847.972804079428, 8287.834243315161, 8322.875156872651, 8383.664653413856, 8001.0, 8101.108878023413, 8173.427860049799, 8199.79500512486, 8209.179082091803, 35659.0, 35731.821340893126, 35755.28489430197, 35826.0, 35868.28263434717, 3606.1886075632774, 3606.783592984403, 3635.781853088823, 3636.5090712753968, 3639.8180090995343, 29873.551896721565, 29974.0, 30073.0, 30162.793488260442, 30215.395692086255, 2827.476916770404, 2840.9431811363656, 3100.224943764059, 3143.7170654640963, 3144.5754823099046, 285377.0, 285840.0, 286183.0, 286926.0, 287443.0, 44913.0, 47786.522134778905, 48284.275735863994, 48471.27293090607, 48483.75758121187, 49774.0045199094, 49780.50219497831, 49983.75008124936, 50000.0, 50025.74987125041, 13919.164850109026, 14055.648608784657, 14062.71874562503, 14062.718745625234, 14067.467855785851, 11001.339919604688, 11011.0, 11220.270682405617, 11226.270292430965, 11272.943635281936, 11405.714857128472, 13220.867791322156, 13534.93232533831, 13576.93211533936, 13589.728205435837, 5148.253503242009, 6010.068439391901, 6043.969780151071, 6054.727537260834, 6088.4215999479975, 906.5920335848895, 972.2756546372998, 977.5112443778111, 1014.9441780701984, 1079.3523885668535, 22019.0, 24531.14141005056, 24540.87729561341, 24631.87684061568, 24641.383965401008, 958.5973890965811, 1119.0823524709822, 1123.662901129658, 1161.8431511745805, 1168.2581560709011, 11050.944745276383, 11660.901037813275, 12368.257904525757, 12635.43140558677, 12653.30406827615, 340171.0, 341053.0, 341165.0, 341340.0, 342427.0, 53847.46152538503, 54087.45912540903, 54150.0, 54179.45820541823, 54243.45756542463, 1503.5639664497392, 1526.7404541227795, 1553.3087775939825, 1559.6412825050302, 1562.6015366081576, 24352.51294974055, 24424.023039078238, 24492.265232043068, 24492.6326105105, 24663.260102196964, 31070.844645776626, 38495.615043849204, 38577.421338680506, 38582.80708596551, 38618.80690596641, 272850.0, 274151.0, 274346.0, 275263.0, 275537.0, 37184.6281537192, 37447.43828842515, 37489.81255093707, 37507.81246093752, 37565.87302380933, 35623.465648015816, 44872.77563612161, 45474.863128422054, 47095.293570595786, 47345.526544734115, 25889.482210356065, 29990.70009299879, 31654.525182122747, 31668.683313166573, 31725.5241171378, 30703.846480767454, 30811.459427028112, 30838.845805770827, 31710.36579268448, 32005.839970799996, 37950.43074353942, 38029.0, 38042.0, 38131.618683812805, 38160.23679526449, 30024.234719214746, 31587.684123159393, 32001.039968800975, 32080.67919320777, 32364.514532282505, 24902.0, 25025.624615631143, 25403.110891118722, 25446.872765636053, 25557.361065972756], "value": 17321.95327100338, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.57999160822374, 440.79723327269454, 442.3652059294912, 457.73222664741127, 458.19129236896885, 19863.404097877068, 21297.467563310907, 21854.01656925438, 22031.779682203174, 22137.667934980967, 22748.862556872173, 22755.431114222152, 22888.313350599467, 23057.769422305773, 24183.186261030405, 5233.685978841263, 5932.970335148319, 6021.187139736135, 6180.381961803829, 6180.505559555225, 5960.433758792908, 6137.171481849938, 6138.907916381258, 6141.877162456748, 6159.969200154015, 5841.620294680833, 6050.848728781772, 6099.57302988791, 6101.877962440749, 6124.1732366130445, 5994.760209591611, 6001.729922153513, 6010.459058684695, 6010.969945150268, 6058.485028772559, 4600.861974140781, 5257.842264732064, 5301.966116607263, 5379.65032272901, 5391.892162156774, 27500.0, 27642.170734877986, 27689.892404303802, 27769.722302776914, 28189.57715634267, 2224.5773303072365, 2277.6241920083166, 2292.3810571145696, 2293.0942277800177, 2299.977000229993, 17725.556861078447, 17938.730919036225, 18128.90935545327, 18308.176132074088, 18417.987010714405, 2124.5857057873723, 2141.5288636499945, 2188.190369563256, 2200.7799220078023, 2202.1631779923687, 123553.0, 150318.0, 151103.0, 151812.0, 171202.99999999875, 10642.25504214713, 10931.852155523715, 12357.0, 12914.806277905838, 13101.475940962446, 16560.50318490423, 16809.747853782203, 16870.662586748444, 16875.409360672562, 16876.38416045515, 8916.331275154389, 9183.448993060305, 9189.954050229706, 9214.585343659552, 9290.349675522784, 6960.234374218832, 8467.40728149036, 8642.395032347675, 8721.345899057596, 8919.777005574913, 7983.5209887406945, 8106.351491880753, 8123.7156699515235, 8152.103268640445, 8158.592070396457, 1995.9222020108978, 2189.6168170570086, 2208.3043841189974, 2212.623853944833, 2405.3625789165885, 941.3034354577563, 953.2326477185793, 953.7410617984333, 960.5149399553181, 985.8028394321165, 20732.274370397266, 21345.0, 21446.46383840385, 21495.78504214938, 21595.892020540112, 972.2562239886493, 981.8085473332775, 1000.1548691355792, 1010.1211945607369, 1014.0467960117613, 10309.96900309978, 10647.307924985005, 11051.944740276247, 11069.0, 11083.72290692739, 535216.0, 537171.3141434268, 540833.0, 542131.0, 549166.0, 99587.0, 132232.33883830713, 137690.0, 139691.0, 139702.1688574817, 1093.305750848217, 1132.8753837077916, 1243.0614885761192, 1275.101053757099, 1276.0238417610535, 18230.72653910219, 19738.210471580976, 20258.987050647702, 20943.057562409424, 21340.61286016404, 42187.578124218366, 54570.45429545654, 56803.71598141983, 57821.84356312766, 58647.70676146592, 78680.0, 78936.60531697304, 80014.0, 80665.0, 81107.18892810996, 8680.131986801522, 9040.095990400907, 9184.632614695338, 9213.216876565568, 9313.25493960495, 14192.787108193179, 14243.287835607971, 14261.073030253001, 14339.426422942965, 14641.780373294621, 11682.24065435744, 11869.169158158846, 11915.702107447383, 12012.699682508008, 12039.217450865666, 10673.0, 11024.944875275572, 11200.775984480428, 11271.323720576791, 11460.140489463156, 11950.880491194977, 12054.521442276615, 12090.274583525017, 12170.452329645364, 12184.817227741769, 8771.649134034822, 8990.775230619025, 8997.846711864215, 9098.0, 9293.303002274992, 11764.117691173367, 11962.880371196177, 11994.820077699016, 12068.939655301667, 12161.209521381083], "value": 10492.008019547255, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.0, 1431.5132854829362, 1453.2806260900857, 1455.0105927968991, 1458.4385011770469, 11711.53153873845, 11918.225315354495, 12232.633021009373, 12267.509299628015, 12508.437120329585, 10310.432926189056, 12132.575359862407, 12176.0, 12363.50545978162, 12386.938065309683, 2541.3773625461786, 2594.221733479958, 2596.298999270196, 2599.0, 2608.2827222513865, 2530.962035569468, 2564.5512035393826, 2583.173384516955, 2583.8966441342322, 2584.4572639745675, 2542.2627438042946, 2560.0783717861555, 2597.766201041906, 2599.090318388568, 2601.609758536216, 2522.445062086338, 2546.363409147704, 2562.3337932137592, 2570.588705807062, 2574.768270855613, 2449.6448015037718, 2544.0, 2552.834065785718, 2554.7572980566815, 2559.1043134902634, 11794.0, 11882.108841836896, 11895.881041189565, 11918.821217681741, 11921.344326062062, 6624.072629831828, 6862.9313706863295, 6867.622280774555, 6868.0, 6872.965635171792, 4073.531543872463, 4402.7358358498595, 4477.082198149372, 4480.260756975095, 4481.865544033684, 1221.4686611324103, 1235.8702336254653, 1291.373683763374, 1297.5264028629576, 1301.954431594899, 15040.849591504055, 15639.843601563953, 16530.421435249707, 16593.170341482877, 16615.0, 5516.724163791794, 5622.690752008679, 5836.678982655994, 5842.649441033466, 5861.853453663693, 11648.767024659459, 11877.109216808602, 11888.762224755457, 11899.583514576949, 11906.166568340306, 5582.748776305076, 6529.0, 6616.735330586819, 6634.966825165843, 6641.435477984329, 5243.4232234454175, 5991.281046274475, 6126.816195514141, 6271.749130034838, 6319.399657032666, 3749.456328832304, 4412.7352358857925, 4494.505604383515, 4500.9099818004115, 4536.160810250114, 1304.3543445994294, 1323.543377534749, 1324.1065276654285, 1329.7008173161155, 1335.5058628307588, 796.6377494484497, 798.7483942558192, 816.8366326734676, 822.1039067416544, 849.227203245044, 6269.46709529686, 6301.936980630227, 6572.418032172477, 7159.785206443815, 7543.7736867894055, 944.9291303152291, 973.7955029443755, 974.5176137811828, 979.7942432089341, 980.8332583460829, 4154.854580089683, 4398.164348773723, 4623.237165867594, 4625.7687115644085, 4641.559051890065, 14693.632659183391, 14809.851901481063, 15260.23698815077, 15350.692986140215, 15393.384264629289, 14633.0, 14744.262786860614, 15299.61750956235, 15420.46028389001, 15435.0, 8661.30709543235, 8728.869066964, 8798.032216456184, 8803.515806630698, 8814.118588141135, 7005.649717514002, 7064.646767661494, 7377.372923301365, 7381.8154546136775, 7407.296306850841, 13537.93231033878, 13572.321383930961, 13747.793783093062, 13808.30738006079, 14005.22971236551, 13823.447062117804, 13923.930380348034, 14028.509002184874, 14099.0, 14104.929475352557, 9372.109649583279, 9538.093881081286, 9546.42721436716, 9554.426734395958, 9558.56986435598, 8948.418352807046, 11203.551857925591, 11292.887071129513, 11320.773584528428, 11345.20583559176, 9188.770280742767, 9303.534823258675, 9351.971283158982, 9395.342326037386, 9445.0, 9066.773330666521, 9141.99438061826, 9196.954015229881, 9242.72271831846, 9264.95367523158, 8039.959800200962, 8262.628181731716, 8290.129536398592, 8383.161683831568, 8394.24451799341, 6031.0585041618715, 6106.175666284995, 6149.169862068564, 6265.216847894013, 6378.266499352634, 6020.096985452217, 6200.937990620217, 6258.4680302173, 6288.811335659938, 6335.669509403077], "value": 5507.208172776939, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.24545717621464, 388.5609261534466, 402.3100382843423, 413.40056917469667, 414.81126087630133, 6091.664958427285, 6286.022515008337, 6453.709583068761, 6479.449246814021, 6484.578502397341, 5677.013045434096, 5678.517326027282, 5723.685197314146, 6162.969185154079, 6362.14111095002, 4828.517148285178, 4860.173770459013, 4885.975570122162, 4904.239842824368, 4907.803687852499, 4597.655175861807, 4805.495422980589, 4826.444958829727, 4848.054629347279, 4848.490908454597, 4029.7380670256484, 4543.750093744843, 4803.879903002418, 4819.108464933998, 4859.951400486003, 3815.2369526094612, 4193.895152621179, 4806.327114204014, 4824.734639594821, 4852.708837469745, 3750.868719594801, 3882.786446745428, 3900.2979463696524, 3928.2339943710986, 3935.252302062599, 4949.7030178189425, 5051.419086805027, 5859.7949071782705, 6257.0, 6266.0, 5111.386633603991, 5478.369987451414, 5501.284832971728, 5510.173473978935, 5518.944810551883, 5250.606204534675, 6197.96901015492, 6558.213014438298, 6698.229703584053, 6831.385175334244, 4459.687821852472, 4486.394336764528, 4487.439070116236, 4496.089162106125, 4498.797554110073, 6299.622022678654, 6589.835254118685, 6643.169603799525, 7177.210506844243, 7229.879368697861, 5101.795928162906, 5152.583039664048, 5153.2270159476375, 5163.483651634881, 5185.688858668492, 4521.8191272349395, 5130.486951304914, 5251.264822924793, 5294.338207724035, 5298.966701493172, 4673.579377856009, 5598.776048957996, 5727.140928860621, 5824.689444874954, 5909.143174239797, 6796.388325050671, 6808.761693340709, 6840.555363901331, 6885.552439091443, 6913.41235994936, 1578.9921050394905, 1701.4091824144464, 1765.938192163268, 1790.6687262856153, 1806.2142967809045, 991.6578780320777, 1239.0954603139758, 1242.8384310039637, 1244.539520377466, 1245.906557008228, 5673.716314184274, 5697.888911662269, 6112.572119951649, 6523.314327405001, 6528.151340325728, 6338.58799178052, 6879.213804136733, 7007.649617519103, 7025.473089518307, 7061.399781018568, 5899.274826697569, 6408.807735767934, 6453.290138084807, 6518.413342799171, 6562.376574225441, 2879.4097210071886, 3569.8750543730844, 3662.7069834413182, 3675.320065787838, 3675.8162091895297, 5779.826605201851, 6086.0, 6924.58452492852, 7193.568385896863, 7254.637268136677, 5922.644641321534, 6252.874942501216, 7197.4961752677855, 7206.87397217822, 7224.0, 5629.802956896571, 6507.446867016357, 6976.19773726028, 7195.0, 7280.817979550554, 6134.325224225242, 6309.589876658002, 7040.331168538981, 7054.858902822018, 7071.82320441993, 6268.780592679234, 6792.573559552549, 6931.930680693128, 6938.895916561357, 6946.2011868635755, 6621.172353455818, 6824.146981627297, 7224.74713385029, 7225.313595208447, 7302.853942921006, 4011.338129208648, 4435.0, 4705.576498115186, 5000.949800541782, 5101.540861322499, 6081.969590152021, 6252.781152659635, 6315.873682526416, 6983.546069505466, 7010.859782804418, 5807.361190268983, 6205.5345849060595, 6551.672416379257, 6700.899486507608, 6793.422559082335, 6647.302033286629, 6700.296468870698, 6705.228898676714, 6722.831929201809, 6750.8649827004165, 6570.441512471493, 6571.835704107244, 6577.566300468857, 6585.670716464253, 6591.967040164768, 5714.971425142848, 6386.744530218739, 6723.899141512979, 6734.73061077571, 6794.810908090965, 4985.950140498694, 5803.564732645153, 5958.0, 6597.50518711089, 6643.368879956396], "value": 4951.461297347837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.239510908655, 818.9795255118621, 821.2444551013067, 863.3869952333845, 870.6865528409771, 16306.266218020188, 16925.32298708052, 16944.23750931208, 18212.998285094316, 19312.227510899582, 17725.55686107848, 18077.457676269732, 18191.27234910602, 18402.539936501595, 18526.907365463187, 967.5500892085162, 1022.7136401807505, 1023.2376879224951, 1024.861643678105, 1026.928115031948, 904.3579058868179, 1005.4419797012655, 1020.7907378987289, 1021.8416145497459, 1022.560299071399, 1065.8507808906759, 1085.4626959654981, 1085.6688709943464, 1086.684861390196, 1089.1559041742673, 1059.5549869054978, 1077.4181941751444, 1077.8383242513607, 1079.1528650009743, 1079.5196137718717, 796.8764841449585, 799.3285640062376, 801.5471258738852, 802.1016461563087, 804.031142473324, 15218.695626087527, 16913.985160890385, 16970.981741095573, 17008.06455644939, 17075.743863842054, 16794.580135496588, 17275.654486910316, 17308.0, 17556.561085972826, 17577.384791532237, 14163.78754318686, 14269.64325891861, 14303.0, 14421.639459013504, 14585.416583336653, 3560.8753693620597, 3604.9725828138853, 3612.8735494257576, 3618.2220822523022, 3635.8727444539577, 94516.0, 122363.3881830594, 123736.76263237433, 125329.0, 126612.0, 13186.802197967038, 13332.200067995951, 13390.93304533471, 13473.393697283645, 13542.458301667823, 23402.531949360917, 24921.62617560738, 26251.73748262531, 26664.73335266661, 26832.060877869182, 6404.679766011681, 6719.899201511981, 7783.532988020737, 7783.688652453839, 7790.493617914817, 3174.730147937405, 3452.4476083826466, 3856.2094770571966, 3978.4827972363987, 3979.8806035818393, 4328.220920234388, 4390.100029493946, 4395.8021889014435, 4422.248217802981, 4426.1369033038245, 871.5032431513977, 895.4671970177864, 896.1307531694246, 896.7713233125511, 897.3718397122041, 651.2478087808589, 656.9540132190797, 668.4117976180929, 676.3236763236763, 687.7077242172057, 9802.264830137769, 11278.323300601991, 11771.705707357214, 11823.0, 12134.211276267015, 655.983600409984, 658.7845425190516, 659.6932426421737, 662.5891946992799, 687.1376422589618, 5020.723860187652, 5049.84850454487, 5088.92366614501, 5093.0, 5269.604779641542, 111059.44470277597, 112948.0, 113857.0, 114195.0, 114241.85758142479, 81375.0, 83352.91500222428, 89377.21245575183, 91251.54374228219, 97917.0, 79138.0, 81073.89261073846, 83188.58405707932, 84218.0, 84587.15412845917, 10741.409222492837, 11972.700682483008, 12485.188462749893, 12584.244945303311, 12586.118971672262, 40678.77963661095, 40742.0, 40780.0, 40998.59001410067, 41332.58667413287, 51938.74030629823, 52622.736886315324, 52631.0, 52862.73568632132, 53393.0, 16039.0, 16086.597835054217, 16404.425845095364, 16561.00633961966, 16585.087820170007, 16481.17594120265, 16733.414330498374, 17168.656626867643, 17187.742183867504, 17327.82672173262, 16408.9210463544, 16470.917645412177, 16612.169391530617, 16865.494035178963, 16876.24056917466, 15845.52463426099, 16094.395046046475, 16237.269322880262, 16350.509484715478, 16426.671466570842, 12655.0, 15937.920310398375, 16038.759418608497, 16065.839341606434, 16281.67436651284, 11529.538818447503, 11619.76760464769, 11964.227287599562, 12071.517139314681, 12086.274823510617, 12687.873121268669, 13827.654308642364, 14129.717405652034, 14132.717345653235, 14198.858011419754], "value": 8370.011859754646, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1598.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 113.0, 111.0, 114.0, 113.0, 121.0, 164.0, 152.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 108.0, 149.0, 92.0, 127.0, 145.0, 149.0, 144.0, 111.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 279.0, 267.0, 235.0, 269.0, 196.0, 273.0, 279.0, 208.0, 195.0], "value": 238.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 77.0, 57.0, 93.0, 51.0, 65.0, 58.0, 51.0, 94.0, 48.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 71.0, 82.0, 72.0, 70.0, 70.0, 93.0, 71.0, 91.0, 95.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 102.0, 89.0, 90.0, 86.0, 137.0, 81.0, 137.0, 136.0], "value": 103.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 98.0, 97.0, 96.0, 120.0, 136.0, 154.0, 97.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 91.0, 141.0, 146.0, 89.0, 91.0, 91.0, 91.0, 90.0], "value": 101.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 52.0, 50.0, 50.0, 53.0, 54.0, 66.0, 47.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 63.0, 59.0, 59.0, 61.0, 56.0, 55.0, 58.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 85.0, 80.0, 85.0, 87.0, 83.0, 103.0, 110.0, 109.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 141.0, 145.0, 157.0, 141.0, 149.0, 149.0, 223.0, 144.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 238.0, 242.0, 183.0, 189.0, 185.0, 186.0, 214.0, 234.0, 195.0], "value": 208.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 80.0, 108.0, 78.0, 103.0, 103.0, 76.0, 71.0, 70.0], "value": 82.8, "unit": "score"}]}]} 10:39:08 INFO - Return code: 0 10:39:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:39:08 INFO - # TBPL SUCCESS # 10:39:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:39:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:39:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:39:08 INFO - 2017-05-14 10:39:08.104 screenresolution[1674:11819] starting screenresolution argv=screenresolution get 10:39:08 INFO - 2017-05-14 10:39:08.107 screenresolution[1674:11819] Display 0: 1600x1200x32@60 10:39:08 INFO - 2017-05-14 10:39:08.113 screenresolution[1675:11822] starting screenresolution argv=screenresolution list 10:39:08 INFO - Available Modes on Display 0 10:39:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:39:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:39:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:39:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:39:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:39:08 INFO - Intel Iris: 10:39:08 INFO - Chipset Model: Intel Iris 10:39:08 INFO - Type: GPU 10:39:08 INFO - Bus: Built-In 10:39:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:39:08 INFO - Vendor: Intel (0x8086) 10:39:08 INFO - Device ID: 0x0a2e 10:39:08 INFO - Revision ID: 0x0009 10:39:08 INFO - Displays: 10:39:08 INFO - Display: 10:39:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:39:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:39:08 INFO - Display Serial Number: 1600x1200 60 10:39:08 INFO - Main Display: Yes 10:39:08 INFO - Mirror: Off 10:39:08 INFO - Online: Yes 10:39:08 INFO - Rotation: Supported 10:39:08 INFO - Return code: 0 10:39:08 INFO - Running post-action listener: _resource_record_post_action 10:39:08 INFO - [mozharness: 2017-05-14 17:39:08.350530Z] Finished run-tests step (success) 10:39:08 INFO - Running post-run listener: _resource_record_post_run 10:39:08 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 34042880; Write bytes: 530585600; Read time: 341; Write time: 1627 10:39:08 INFO - TinderboxPrint: CPU usage
54.1% 10:39:08 INFO - TinderboxPrint: I/O read bytes / time
34,042,880 / 341 10:39:08 INFO - TinderboxPrint: I/O write bytes / time
530,585,600 / 1,627 10:39:08 INFO - TinderboxPrint: CPU idle
2,199.3 (45.9%) 10:39:08 INFO - TinderboxPrint: CPU system
965.0 (20.1%) 10:39:08 INFO - TinderboxPrint: CPU user
1,629.3 (34.0%) 10:39:08 INFO - TinderboxPrint: Swap in / out
244,858,880 / 0 10:39:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205832704; Write bytes: 199864320; Read time: 16106; Write time: 413 10:39:08 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 33055744; Write bytes: 313139200; Read time: 328; Write time: 1160 10:39:08 INFO - Running post-run listener: _upload_blobber_files 10:39:08 INFO - Blob upload gear active. 10:39:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:39:08 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 10:39:08 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:39:08 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:39:09 INFO - (blobuploader) - INFO - Open directory for files ... 10:39:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:39:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:39:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:39:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:39:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:39:10 INFO - (blobuploader) - INFO - Done attempting. 10:39:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:39:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:39:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:39:11 INFO - (blobuploader) - INFO - Done attempting. 10:39:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:39:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:39:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:39:12 INFO - (blobuploader) - INFO - Done attempting. 10:39:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:39:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:39:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:39:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:39:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:39:14 INFO - (blobuploader) - INFO - Done attempting. 10:39:14 INFO - (blobuploader) - INFO - Iteration through files over. 10:39:14 INFO - Return code: 0 10:39:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:39:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:39:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe5325166b5a91a5d2b64d6dcaad1ce280083a54142c0dc815d342ada4dc2b21a9f8a2ad03ed2d9da21e9bd67cb774797f521b373081cadc93e9f33eb9076eae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c387a5af6dbb236fae139159ee8590f32d9d521783b63bd5177378f16f9343f2805aca91d6817a0b127152b3a82294dc08da124f7be6220a5d42a1ff87474c2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c363ff69e2b604ce52450916b15319dcd1eef82010313823aedfd7b5731315eee6312fef87f8ffaff83276ee9ac4e725e783e9ec54208397fbdb90ac61e64875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c949c2845526a8fbea06e3e70ebfe40c39cc64b363f899394e8f517943b0f97711891695fe9b4807fa5479c5fa601d8cc5e4eae04e38590995f561630a580ac"} 10:39:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:39:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:39:14 INFO - Contents: 10:39:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe5325166b5a91a5d2b64d6dcaad1ce280083a54142c0dc815d342ada4dc2b21a9f8a2ad03ed2d9da21e9bd67cb774797f521b373081cadc93e9f33eb9076eae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c387a5af6dbb236fae139159ee8590f32d9d521783b63bd5177378f16f9343f2805aca91d6817a0b127152b3a82294dc08da124f7be6220a5d42a1ff87474c2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c363ff69e2b604ce52450916b15319dcd1eef82010313823aedfd7b5731315eee6312fef87f8ffaff83276ee9ac4e725e783e9ec54208397fbdb90ac61e64875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c949c2845526a8fbea06e3e70ebfe40c39cc64b363f899394e8f517943b0f97711891695fe9b4807fa5479c5fa601d8cc5e4eae04e38590995f561630a580ac"} 10:39:14 INFO - Running post-run listener: copy_logs_to_upload_dir 10:39:14 INFO - Copying logs to upload dir... 10:39:14 INFO - mkdir: /builds/slave/test/build/upload/logs 10:39:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.236025 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-14 10:39:14.555567) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 10:39:14.559030) ========= 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.ow6PxNaS1q/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.QbgmNdNppj/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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/Firefox/sha512/fe5325166b5a91a5d2b64d6dcaad1ce280083a54142c0dc815d342ada4dc2b21a9f8a2ad03ed2d9da21e9bd67cb774797f521b373081cadc93e9f33eb9076eae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c387a5af6dbb236fae139159ee8590f32d9d521783b63bd5177378f16f9343f2805aca91d6817a0b127152b3a82294dc08da124f7be6220a5d42a1ff87474c2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c363ff69e2b604ce52450916b15319dcd1eef82010313823aedfd7b5731315eee6312fef87f8ffaff83276ee9ac4e725e783e9ec54208397fbdb90ac61e64875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c949c2845526a8fbea06e3e70ebfe40c39cc64b363f899394e8f517943b0f97711891695fe9b4807fa5479c5fa601d8cc5e4eae04e38590995f561630a580ac"} build_url:https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/Ihp-PaV5RrOEZPxmpLz0Bw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe5325166b5a91a5d2b64d6dcaad1ce280083a54142c0dc815d342ada4dc2b21a9f8a2ad03ed2d9da21e9bd67cb774797f521b373081cadc93e9f33eb9076eae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c387a5af6dbb236fae139159ee8590f32d9d521783b63bd5177378f16f9343f2805aca91d6817a0b127152b3a82294dc08da124f7be6220a5d42a1ff87474c2c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c363ff69e2b604ce52450916b15319dcd1eef82010313823aedfd7b5731315eee6312fef87f8ffaff83276ee9ac4e725e783e9ec54208397fbdb90ac61e64875", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c949c2845526a8fbea06e3e70ebfe40c39cc64b363f899394e8f517943b0f97711891695fe9b4807fa5479c5fa601d8cc5e4eae04e38590995f561630a580ac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 10:39:14.592423) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:39:14.592779) ========= 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.ow6PxNaS1q/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.QbgmNdNppj/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.005524 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:39:14.641593) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 10:39:14.642008) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 10:39:14.642371) ========= ========= Total master_lag: 0.38 =========