builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0285 starttime: 1495466383.12 results: success (0) revision: 8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:43.122565) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:43.123081) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:43.123385) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:43.153959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 08:19:43.154439) ========= 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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-22 08:19:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.48M=0.001s 2017-05-22 08:19:43 (9.48 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.157138 ========= master_lag: 1.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 08:19:44.537241) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:44.537711) ========= 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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.028778 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:44.587394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 08:19:44.587756) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f --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 8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qrNO3jyNM3/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.q8xgBeY6PP/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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-22 08:19:44,686 truncating revision to first 12 chars 2017-05-22 08:19:44,687 Setting DEBUG logging. 2017-05-22 08:19:44,687 attempt 1/10 2017-05-22 08:19:44,687 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8f4d2d35cb31?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 08:19:45,438 unpacking tar archive at: mozilla-central-8f4d2d35cb31/testing/mozharness/ program finished with exit code 0 elapsedTime=1.038189 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 08:19:45.651123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:45.651491) ========= script_repo_revision: 8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:45.651950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:45.652281) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 08:19:45.671223) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-22 08:19:45.671669) ========= /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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:19:45 INFO - MultiFileLogger online at 20170522 08:19:45 in /builds/slave/test 08:19:45 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 08:19:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:19:45 INFO - {'append_to_log': False, 08:19:45 INFO - 'base_work_dir': '/builds/slave/test', 08:19:45 INFO - 'blob_upload_branch': 'Firefox', 08:19:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:19:45 INFO - 'branch': 'Firefox', 08:19:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:19:45 INFO - 'config_files': ('talos/mac_config.py',), 08:19:45 INFO - 'default_actions': ('clobber', 08:19:45 INFO - 'read-buildbot-config', 08:19:45 INFO - 'download-and-extract', 08:19:45 INFO - 'populate-webroot', 08:19:45 INFO - 'create-virtualenv', 08:19:45 INFO - 'install', 08:19:45 INFO - 'run-tests'), 08:19:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:19:45 INFO - 'download_minidump_stackwalk': True, 08:19:45 INFO - 'download_symbols': 'ondemand', 08:19:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:19:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:19:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:19:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:19:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:19:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:19:45 INFO - 'gecko_profile': False, 08:19:45 INFO - 'gecko_profile_interval': 0, 08:19:45 INFO - 'installer_path': 'installer.exe', 08:19:45 INFO - 'log_level': 'info', 08:19:45 INFO - 'log_name': 'talos', 08:19:45 INFO - 'log_to_console': True, 08:19:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:19:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:19:45 INFO - 'opt_config_files': (), 08:19:45 INFO - 'pip_index': False, 08:19:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:45 INFO - 'cmd': ('bash', 08:19:45 INFO - '-c', 08:19:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:45 INFO - 'enabled': True, 08:19:45 INFO - 'halt_on_failure': False, 08:19:45 INFO - 'name': 'check_screen_resolution'},), 08:19:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:45 INFO - 'cmd': ('bash', 08:19:45 INFO - '-c', 08:19:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:45 INFO - 'enabled': True, 08:19:45 INFO - 'halt_on_failure': False, 08:19:45 INFO - 'name': 'check_screen_resolution'},), 08:19:45 INFO - 'run_cmd_checks_enabled': True, 08:19:45 INFO - 'suite': 'dromaeojs-e10s', 08:19:45 INFO - 'system_bits': '32', 08:19:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:19:45 INFO - 'title': 't-yosemite-r7-0285', 08:19:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:19:45 INFO - 'use_talos_json': True, 08:19:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:19:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:19:45 INFO - 'work_dir': 'build'} 08:19:45 INFO - [mozharness: 2017-05-22 15:19:45.879310Z] Running clobber step. 08:19:45 INFO - Running pre-action listener: _resource_record_pre_action 08:19:45 INFO - Running main action method: clobber 08:19:45 INFO - rmtree: /builds/slave/test/build 08:19:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:19:48 INFO - Running post-action listener: _resource_record_post_action 08:19:48 INFO - [mozharness: 2017-05-22 15:19:48.335348Z] Finished clobber step (success) 08:19:48 INFO - [mozharness: 2017-05-22 15:19:48.335496Z] Running read-buildbot-config step. 08:19:48 INFO - Running pre-action listener: _resource_record_pre_action 08:19:48 INFO - Running main action method: read_buildbot_config 08:19:48 INFO - Using buildbot properties: 08:19:48 INFO - { 08:19:48 INFO - "project": "", 08:19:48 INFO - "product": "firefox", 08:19:48 INFO - "who": "cbook@mozilla.com", 08:19:48 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:19:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:19:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:19:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:19:48 INFO - "stage_platform": "macosx64", 08:19:48 INFO - "basedir": "/builds/slave/test", 08:19:48 INFO - "buildnumber": 62, 08:19:48 INFO - "platform": "yosemite_r7", 08:19:48 INFO - "parent_task_id": "RUflUMD3SZSJyN5VXyPsxg", 08:19:48 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:19:48 INFO - "slavebuilddir": "test", 08:19:48 INFO - "taskId": "HLfVh5LcQfe7qqEBmtzJhQ", 08:19:48 INFO - "branch": "mozilla-central", 08:19:48 INFO - "script_repo_revision": "production", 08:19:48 INFO - "revision": "8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f", 08:19:48 INFO - "slavename": "t-yosemite-r7-0285", 08:19:48 INFO - "repo_path": "mozilla-central" 08:19:48 INFO - } 08:19:48 INFO - Finding installer, test and symbols from parent task. 08:19:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HLfVh5LcQfe7qqEBmtzJhQ'}, attempt #1 08:19:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HLfVh5LcQfe7qqEBmtzJhQ'}, attempt #1 08:19:49 INFO - Task dependencies: PM0VLK43T3e8CRtQSCeIvg RUflUMD3SZSJyN5VXyPsxg 08:19:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg'}, attempt #1 08:19:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:19:50 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/test_packages.json 08:19:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/target.crashreporter-symbols.zip 08:19:50 INFO - Running post-action listener: _resource_record_post_action 08:19:50 INFO - [mozharness: 2017-05-22 15:19:50.094119Z] Finished read-buildbot-config step (success) 08:19:50 INFO - [mozharness: 2017-05-22 15:19:50.094243Z] Running download-and-extract step. 08:19:50 INFO - Running pre-action listener: _resource_record_pre_action 08:19:50 INFO - Running main action method: download_and_extract 08:19:50 INFO - mkdir: /builds/slave/test/build/tests 08:19:50 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')]} 08:19:50 INFO - trying https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:19:50 INFO - Downloading https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/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 08:19:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/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 08:19:50 INFO - Downloaded 1325 bytes. 08:19:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:19:50 INFO - Using the following test package requirements: 08:19:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:19:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:19:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:19:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:19:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'jsshell-mac.zip'], 08:19:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:19:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:19:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:19:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:19:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:19:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:19:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:19:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:19:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:19:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:19:50 INFO - Fetch https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:19:52 INFO - Content-Length response header: 57622672 08:19:52 INFO - Bytes received: 57622672 08:20:10 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 08:20:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:20:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:20:10 INFO - Fetch https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:20:12 INFO - Content-Length response header: 57622672 08:20:12 INFO - Bytes received: 57622672 08:20:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:20:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:20:29 INFO - Fetch https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:20:30 INFO - Content-Length response header: 14410000 08:20:30 INFO - Bytes received: 14410000 08:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:31 INFO - trying https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:20:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/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 08:20:33 INFO - Downloaded 67990724 bytes. 08:20:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:20:33 INFO - mkdir: /builds/slave/test/properties 08:20:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:20:33 INFO - Writing to file /builds/slave/test/properties/build_url 08:20:33 INFO - Contents: 08:20:33 INFO - build_url:https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:20:33 INFO - Running post-action listener: _resource_record_post_action 08:20:33 INFO - Running post-action listener: set_extra_try_arguments 08:20:33 INFO - [mozharness: 2017-05-22 15:20:33.641221Z] Finished download-and-extract step (success) 08:20:33 INFO - [mozharness: 2017-05-22 15:20:33.641381Z] Running populate-webroot step. 08:20:33 INFO - Running pre-action listener: _resource_record_pre_action 08:20:33 INFO - Running main action method: populate_webroot 08:20:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:20:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:20:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:20:33 INFO - '--tppagecycles', 08:20:33 INFO - '7'], 08:20:33 INFO - 'tests': ['tsvgm']}}, 08:20:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:20:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:20:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:20:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:20:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['damp', 'tps']}, 08:20:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'tests': ['damp', 'tps']}, 08:20:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['dromaeo_dom']}, 08:20:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:20:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:20:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:20:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'tests': ['ts_paint', 'tp5o']}, 08:20:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['a11yr', 08:20:33 INFO - 'ts_paint', 08:20:33 INFO - 'tpaint', 08:20:33 INFO - 'sessionrestore', 08:20:33 INFO - 'sessionrestore_no_auto_restore', 08:20:33 INFO - 'tabpaint']}, 08:20:33 INFO - 'other-e10s': {'tests': ['a11yr', 08:20:33 INFO - 'ts_paint', 08:20:33 INFO - 'tpaint', 08:20:33 INFO - 'sessionrestore', 08:20:33 INFO - 'sessionrestore_no_auto_restore', 08:20:33 INFO - 'tabpaint']}, 08:20:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:20:33 INFO - 'ts_paint', 08:20:33 INFO - 'tpaint', 08:20:33 INFO - 'sessionrestore', 08:20:33 INFO - 'sessionrestore_no_auto_restore', 08:20:33 INFO - 'tabpaint']}, 08:20:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:20:33 INFO - 'ts_paint', 08:20:33 INFO - 'tpaint', 08:20:33 INFO - 'sessionrestore', 08:20:33 INFO - 'sessionrestore_no_auto_restore', 08:20:33 INFO - 'tabpaint']}, 08:20:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['a11yr', 08:20:33 INFO - 'ts_paint', 08:20:33 INFO - 'tpaint', 08:20:33 INFO - 'sessionrestore', 08:20:33 INFO - 'sessionrestore_no_auto_restore', 08:20:33 INFO - 'tabpaint']}, 08:20:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['a11yr', 08:20:33 INFO - 'ts_paint', 08:20:33 INFO - 'tpaint', 08:20:33 INFO - 'sessionrestore', 08:20:33 INFO - 'sessionrestore_no_auto_restore', 08:20:33 INFO - 'tabpaint']}, 08:20:33 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:20:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:20:33 INFO - 'bloom_basic_ref']}, 08:20:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['tsvgx', 08:20:33 INFO - 'tsvgr_opacity', 08:20:33 INFO - 'tart', 08:20:33 INFO - 'tscrollx', 08:20:33 INFO - 'cart', 08:20:33 INFO - 'tsvg_static']}, 08:20:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:20:33 INFO - 'tsvgr_opacity', 08:20:33 INFO - 'tart', 08:20:33 INFO - 'tscrollx', 08:20:33 INFO - 'cart', 08:20:33 INFO - 'tsvg_static']}, 08:20:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'talos_options': ['--disable-e10s'], 08:20:33 INFO - 'tests': ['tp5o']}, 08:20:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:20:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'talos_options': ['--disable-e10s', 08:20:33 INFO - '--xperf_path', 08:20:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:33 INFO - 'tests': ['tp5n']}, 08:20:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:20:33 INFO - 'talos_options': ['--xperf_path', 08:20:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:33 INFO - 'tests': ['tp5n']}}, 08:20:33 INFO - 'talos.zip': {'path': '', 08:20:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:20:33 INFO - Running post-action listener: _resource_record_post_action 08:20:33 INFO - [mozharness: 2017-05-22 15:20:33.662968Z] Finished populate-webroot step (success) 08:20:33 INFO - [mozharness: 2017-05-22 15:20:33.663121Z] Running create-virtualenv step. 08:20:33 INFO - Running pre-action listener: _resource_record_pre_action 08:20:33 INFO - Running main action method: create_virtualenv 08:20:33 INFO - Creating virtualenv /builds/slave/test/build/venv 08:20:33 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 08:20:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:20:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:20:33 INFO - Using real prefix '/tools/python27' 08:20:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:20:34 INFO - Installing distribute.............................................................................................................................................................................................done. 08:20:37 INFO - Installing pip.................done. 08:20:37 INFO - Return code: 0 08:20:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:20:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:20:37 INFO - Reading from file tmpfile_stdout 08:20:37 INFO - Output received: 08:20:37 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) 08:20:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:38 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')]} 08:20:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:38 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:38 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 08:20:38 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 08:20:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:38 INFO - 'HOME': '/Users/cltbld', 08:20:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:38 INFO - 'LOGNAME': 'cltbld', 08:20:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:38 INFO - 'MOZ_NO_REMOTE': '1', 08:20:38 INFO - 'NO_EM_RESTART': '1', 08:20:38 INFO - 'PAGER': '/bin/cat', 08:20:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:38 INFO - 'PWD': '/builds/slave/test', 08:20:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:38 INFO - 'SHELL': '/bin/bash', 08:20:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:38 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:38 INFO - 'USER': 'cltbld', 08:20:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:38 INFO - 'XPC_FLAGS': '0x0', 08:20:38 INFO - 'XPC_SERVICE_NAME': '0', 08:20:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:38 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 08:20:38 INFO - Cleaning up... 08:20:38 INFO - Return code: 0 08:20:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:20:38 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')]} 08:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:38 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')]} 08:20:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:39 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:39 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 08:20:39 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 08:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:39 INFO - 'HOME': '/Users/cltbld', 08:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:39 INFO - 'LOGNAME': 'cltbld', 08:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:39 INFO - 'MOZ_NO_REMOTE': '1', 08:20:39 INFO - 'NO_EM_RESTART': '1', 08:20:39 INFO - 'PAGER': '/bin/cat', 08:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:39 INFO - 'PWD': '/builds/slave/test', 08:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:39 INFO - 'SHELL': '/bin/bash', 08:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:39 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:39 INFO - 'USER': 'cltbld', 08:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:39 INFO - 'XPC_FLAGS': '0x0', 08:20:39 INFO - 'XPC_SERVICE_NAME': '0', 08:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:39 INFO - Downloading/unpacking psutil>=3.1.1 08:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:20:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:41 INFO - Installing collected packages: psutil 08:20:41 INFO - Running setup.py install for psutil 08:20:41 INFO - building 'psutil._psutil_osx' extension 08:20:41 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 08:20:42 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 08:20:42 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 08:20:42 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 08:20:42 INFO - building 'psutil._psutil_posix' extension 08:20:42 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 08:20:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:20:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:20:42 INFO - ^ 08:20:42 INFO - 1 warning generated. 08:20:42 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 08:20:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:42 INFO - Successfully installed psutil 08:20:42 INFO - Cleaning up... 08:20:42 INFO - Return code: 0 08:20:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:20:42 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')]} 08:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:42 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')]} 08:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:42 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:42 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 08:20:42 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 08:20:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:42 INFO - 'HOME': '/Users/cltbld', 08:20:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:42 INFO - 'LOGNAME': 'cltbld', 08:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:42 INFO - 'MOZ_NO_REMOTE': '1', 08:20:42 INFO - 'NO_EM_RESTART': '1', 08:20:42 INFO - 'PAGER': '/bin/cat', 08:20:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:42 INFO - 'PWD': '/builds/slave/test', 08:20:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:42 INFO - 'SHELL': '/bin/bash', 08:20:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:42 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:42 INFO - 'USER': 'cltbld', 08:20:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:42 INFO - 'XPC_FLAGS': '0x0', 08:20:42 INFO - 'XPC_SERVICE_NAME': '0', 08:20:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:20:43 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 08:20:43 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 08:20:43 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 08:20:43 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 08:20:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:20:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:20:44 INFO - Installing collected packages: mozsystemmonitor 08:20:44 INFO - Running setup.py install for mozsystemmonitor 08:20:44 INFO - Successfully installed mozsystemmonitor 08:20:44 INFO - Cleaning up... 08:20:44 INFO - Return code: 0 08:20:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:44 INFO - 'HOME': '/Users/cltbld', 08:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:44 INFO - 'LOGNAME': 'cltbld', 08:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:44 INFO - 'MOZ_NO_REMOTE': '1', 08:20:44 INFO - 'NO_EM_RESTART': '1', 08:20:44 INFO - 'PAGER': '/bin/cat', 08:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:44 INFO - 'PWD': '/builds/slave/test', 08:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:44 INFO - 'SHELL': '/bin/bash', 08:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:44 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:44 INFO - 'USER': 'cltbld', 08:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:44 INFO - 'XPC_FLAGS': '0x0', 08:20:44 INFO - 'XPC_SERVICE_NAME': '0', 08:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:45 INFO - Downloading/unpacking jsonschema==2.5.1 08:20:45 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 08:20:45 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 08:20:45 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 08:20:45 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 08:20:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:20:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:20:46 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 08:20:46 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 08:20:46 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 08:20:46 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 08:20:46 INFO - Downloading functools32-3.2.3-2.tar.gz 08:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:20:46 INFO - warning: no files found matching '*.txt' 08:20:46 INFO - no previously-included directories found matching 'build' 08:20:46 INFO - no previously-included directories found matching 'dist' 08:20:46 INFO - no previously-included directories found matching '.git*' 08:20:46 INFO - Installing collected packages: jsonschema, functools32 08:20:46 INFO - Running setup.py install for functools32 08:20:46 INFO - warning: no files found matching '*.txt' 08:20:46 INFO - no previously-included directories found matching 'build' 08:20:46 INFO - no previously-included directories found matching 'dist' 08:20:46 INFO - no previously-included directories found matching '.git*' 08:20:47 INFO - Successfully installed jsonschema functools32 08:20:47 INFO - Cleaning up... 08:20:47 INFO - Return code: 0 08:20:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:47 INFO - 'HOME': '/Users/cltbld', 08:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:47 INFO - 'LOGNAME': 'cltbld', 08:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:47 INFO - 'MOZ_NO_REMOTE': '1', 08:20:47 INFO - 'NO_EM_RESTART': '1', 08:20:47 INFO - 'PAGER': '/bin/cat', 08:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:47 INFO - 'PWD': '/builds/slave/test', 08:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:47 INFO - 'SHELL': '/bin/bash', 08:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:47 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:47 INFO - 'USER': 'cltbld', 08:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:47 INFO - 'XPC_FLAGS': '0x0', 08:20:47 INFO - 'XPC_SERVICE_NAME': '0', 08:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:20:47 INFO - Cleaning up... 08:20:47 INFO - Return code: 0 08:20:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:47 INFO - 'HOME': '/Users/cltbld', 08:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:47 INFO - 'LOGNAME': 'cltbld', 08:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:47 INFO - 'MOZ_NO_REMOTE': '1', 08:20:47 INFO - 'NO_EM_RESTART': '1', 08:20:47 INFO - 'PAGER': '/bin/cat', 08:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:47 INFO - 'PWD': '/builds/slave/test', 08:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:47 INFO - 'SHELL': '/bin/bash', 08:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:47 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:47 INFO - 'USER': 'cltbld', 08:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:47 INFO - 'XPC_FLAGS': '0x0', 08:20:47 INFO - 'XPC_SERVICE_NAME': '0', 08:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:47 INFO - Downloading/unpacking blobuploader==1.2.4 08:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:49 INFO - Downloading blobuploader-1.2.4.tar.gz 08:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:20:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:20:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:50 INFO - Downloading docopt-0.6.1.tar.gz 08:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:20:50 INFO - Installing collected packages: blobuploader, requests, docopt 08:20:50 INFO - Running setup.py install for blobuploader 08:20:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:20:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:20:50 INFO - Running setup.py install for requests 08:20:50 INFO - Running setup.py install for docopt 08:20:50 INFO - Successfully installed blobuploader requests docopt 08:20:50 INFO - Cleaning up... 08:20:51 INFO - Return code: 0 08:20:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:51 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:51 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 08:20:51 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 08:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:51 INFO - 'HOME': '/Users/cltbld', 08:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:51 INFO - 'LOGNAME': 'cltbld', 08:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:51 INFO - 'MOZ_NO_REMOTE': '1', 08:20:51 INFO - 'NO_EM_RESTART': '1', 08:20:51 INFO - 'PAGER': '/bin/cat', 08:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:51 INFO - 'PWD': '/builds/slave/test', 08:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:51 INFO - 'SHELL': '/bin/bash', 08:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:51 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:51 INFO - 'USER': 'cltbld', 08:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:51 INFO - 'XPC_FLAGS': '0x0', 08:20:51 INFO - 'XPC_SERVICE_NAME': '0', 08:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-3oui3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-lALKzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-gq_i35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-scseT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-1z6CFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Deg3WO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-FQ0Hx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:51 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-5CBZ84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Txsx19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-BDRHIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-YUU5qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-kulcgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-G4G907-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-clImxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-88B1R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-rfsEKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:52 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-H9Vps2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:20:53 INFO - Running setup.py install for manifestparser 08:20:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Running setup.py install for mozcrash 08:20:53 INFO - Running setup.py install for mozdebug 08:20:53 INFO - Running setup.py install for mozdevice 08:20:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Running setup.py install for mozfile 08:20:53 INFO - Running setup.py install for mozhttpd 08:20:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Running setup.py install for mozinfo 08:20:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Running setup.py install for mozInstall 08:20:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Running setup.py install for mozleak 08:20:54 INFO - Running setup.py install for mozlog 08:20:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Running setup.py install for moznetwork 08:20:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Running setup.py install for mozprocess 08:20:54 INFO - Running setup.py install for mozprofile 08:20:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:20:54 INFO - Running setup.py install for mozrunner 08:20:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:20:55 INFO - Running setup.py install for mozscreenshot 08:20:55 INFO - Running setup.py install for moztest 08:20:55 INFO - Running setup.py install for mozversion 08:20:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:20:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:20:55 INFO - Cleaning up... 08:20:55 INFO - Return code: 0 08:20:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:55 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:55 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 08:20:55 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 08:20:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:55 INFO - 'HOME': '/Users/cltbld', 08:20:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:55 INFO - 'LOGNAME': 'cltbld', 08:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:55 INFO - 'MOZ_NO_REMOTE': '1', 08:20:55 INFO - 'NO_EM_RESTART': '1', 08:20:55 INFO - 'PAGER': '/bin/cat', 08:20:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:55 INFO - 'PWD': '/builds/slave/test', 08:20:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:55 INFO - 'SHELL': '/bin/bash', 08:20:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:55 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:55 INFO - 'USER': 'cltbld', 08:20:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:55 INFO - 'XPC_FLAGS': '0x0', 08:20:55 INFO - 'XPC_SERVICE_NAME': '0', 08:20:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:55 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-rW6zHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:55 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-WLyydk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-6UTS7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-3_SJjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-_Jmin6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-i0rIdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-kzFEjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-UBwhf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-s8IHGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-jr2574-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-vvfXbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:56 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)) 08:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:56 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-IuBAsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:57 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)) 08:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:57 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-krIQ9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:57 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)) 08:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:57 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-BVOuU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:57 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)) 08:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:57 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-wAes8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:57 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)) 08:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:57 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-e78_KT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:57 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)) 08:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:57 INFO - Running setup.py (path:/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/pip-Y_J39f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:57 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)) 08:20:57 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)) 08:20:57 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)) 08:20:57 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)) 08:20:57 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)) 08:20:57 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)) 08:20:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:57 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 08:20:57 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 08:20:57 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 08:20:57 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 08:20:59 INFO - Downloading blessings-1.6.tar.gz 08:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:20:59 INFO - Installing collected packages: blessings 08:20:59 INFO - Running setup.py install for blessings 08:20:59 INFO - Successfully installed blessings 08:20:59 INFO - Cleaning up... 08:20:59 INFO - Return code: 0 08:20:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:59 INFO - Reading from file tmpfile_stdout 08:20:59 INFO - Current package versions: 08:20:59 INFO - blessings == 1.6 08:20:59 INFO - blobuploader == 1.2.4 08:20:59 INFO - docopt == 0.6.1 08:20:59 INFO - functools32 == 3.2.3-2 08:20:59 INFO - jsonschema == 2.5.1 08:20:59 INFO - manifestparser == 1.1 08:20:59 INFO - mozInstall == 1.12 08:20:59 INFO - mozcrash == 1.0 08:20:59 INFO - mozdebug == 0.1 08:20:59 INFO - mozdevice == 0.50 08:20:59 INFO - mozfile == 1.2 08:20:59 INFO - mozhttpd == 0.7 08:20:59 INFO - mozinfo == 0.9 08:20:59 INFO - mozleak == 0.1 08:20:59 INFO - mozlog == 3.4 08:20:59 INFO - moznetwork == 0.27 08:20:59 INFO - mozprocess == 0.25 08:20:59 INFO - mozprofile == 0.28 08:20:59 INFO - mozrunner == 6.13 08:20:59 INFO - mozscreenshot == 0.1 08:20:59 INFO - mozsystemmonitor == 0.3 08:20:59 INFO - moztest == 0.8 08:20:59 INFO - mozversion == 1.4 08:20:59 INFO - psutil == 3.1.1 08:20:59 INFO - requests == 1.2.3 08:20:59 INFO - wsgiref == 0.1.2 08:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20: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')]} 08:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:59 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:59 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 08:20:59 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 08:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:59 INFO - 'HOME': '/Users/cltbld', 08:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:59 INFO - 'LOGNAME': 'cltbld', 08:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:59 INFO - 'MOZ_NO_REMOTE': '1', 08:20:59 INFO - 'NO_EM_RESTART': '1', 08:20:59 INFO - 'PAGER': '/bin/cat', 08:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:59 INFO - 'PWD': '/builds/slave/test', 08:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:59 INFO - 'SHELL': '/bin/bash', 08:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:59 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:59 INFO - 'USER': 'cltbld', 08:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:59 INFO - 'XPC_FLAGS': '0x0', 08:20:59 INFO - 'XPC_SERVICE_NAME': '0', 08:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 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)) 08:20:59 INFO - Cleaning up... 08:20:59 INFO - Return code: 0 08:20:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20: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')]} 08:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:59 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 0x108b85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb7c95c7390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, '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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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 08:20:59 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 08:20:59 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 08:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:59 INFO - 'HOME': '/Users/cltbld', 08:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:59 INFO - 'LOGNAME': 'cltbld', 08:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:59 INFO - 'MOZ_NO_REMOTE': '1', 08:20:59 INFO - 'NO_EM_RESTART': '1', 08:20:59 INFO - 'PAGER': '/bin/cat', 08:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:59 INFO - 'PWD': '/builds/slave/test', 08:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:59 INFO - 'SHELL': '/bin/bash', 08:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:20:59 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:59 INFO - 'USER': 'cltbld', 08:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:59 INFO - 'XPC_FLAGS': '0x0', 08:20:59 INFO - 'XPC_SERVICE_NAME': '0', 08:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:21:00 INFO - Cleaning up... 08:21:00 INFO - Return code: 0 08:21:00 INFO - Running post-action listener: _resource_record_post_action 08:21:00 INFO - Running post-action listener: _start_resource_monitoring 08:21:00 INFO - Starting resource monitoring. 08:21:00 INFO - [mozharness: 2017-05-22 15:21:00.245488Z] Finished create-virtualenv step (success) 08:21:00 INFO - [mozharness: 2017-05-22 15:21:00.246287Z] Running install step. 08:21:00 INFO - Running pre-action listener: _resource_record_pre_action 08:21:00 INFO - Running main action method: install 08:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:00 INFO - Reading from file tmpfile_stdout 08:21:00 INFO - Detecting whether we're running mozinstall >=1.0... 08:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:21:00 INFO - Reading from file tmpfile_stdout 08:21:00 INFO - Output received: 08:21:00 INFO - Usage: mozinstall [options] installer 08:21:00 INFO - Options: 08:21:00 INFO - -h, --help show this help message and exit 08:21:00 INFO - -d DEST, --destination=DEST 08:21:00 INFO - Directory to install application into. [default: 08:21:00 INFO - "/builds/slave/test"] 08:21:00 INFO - --app=APP Application being installed. [default: firefox] 08:21:00 INFO - mkdir: /builds/slave/test/build/application 08:21:00 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'] 08:21:00 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 08:21:21 INFO - Reading from file tmpfile_stdout 08:21:21 INFO - Output received: 08:21:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:21:21 INFO - Running post-action listener: _resource_record_post_action 08:21:21 INFO - [mozharness: 2017-05-22 15:21:21.986358Z] Finished install step (success) 08:21:21 INFO - [mozharness: 2017-05-22 15:21:21.986473Z] Running run-tests step. 08:21:21 INFO - Running pre-action listener: _resource_record_pre_action 08:21:21 INFO - Running main action method: run_tests 08:21:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:22 INFO - 2017-05-22 08:21:22.000 screenresolution[1621:7356] starting screenresolution argv=screenresolution get 08:21:22 INFO - 2017-05-22 08:21:22.003 screenresolution[1621:7356] Display 0: 1600x1200x32@60 08:21:22 INFO - 2017-05-22 08:21:22.009 screenresolution[1622:7358] starting screenresolution argv=screenresolution list 08:21:22 INFO - Available Modes on Display 0 08:21:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:22 INFO - Intel Iris: 08:21:22 INFO - Chipset Model: Intel Iris 08:21:22 INFO - Type: GPU 08:21:22 INFO - Bus: Built-In 08:21:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:22 INFO - Vendor: Intel (0x8086) 08:21:22 INFO - Device ID: 0x0a2e 08:21:22 INFO - Revision ID: 0x0009 08:21:22 INFO - Displays: 08:21:22 INFO - Display: 08:21:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:22 INFO - Display Serial Number: 1600x1200 60 08:21:22 INFO - Main Display: Yes 08:21:22 INFO - Mirror: Off 08:21:22 INFO - Online: Yes 08:21:22 INFO - Rotation: Supported 08:21:22 INFO - Return code: 0 08:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:21:22 INFO - Python 2.7.3 08:21:22 INFO - Return code: 0 08:21:22 INFO - grabbing minidump binary from tooltool 08:21:22 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')]} 08:21:22 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 0x108895d30>, '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 0x108849db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10888abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:21:22 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 08:21:22 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 08:21:22 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 08:21:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:21:22 INFO - Return code: 0 08:21:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:21:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:21:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:21:22 INFO - ENV: RUST_BACKTRACE is now 1 08:21:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:21:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:21:22 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/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--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 08:21:22 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/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0285 --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 08:21:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qrNO3jyNM3/Render', 08:21:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:22 INFO - 'HOME': '/Users/cltbld', 08:21:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:22 INFO - 'LOGNAME': 'cltbld', 08:21:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:21:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:22 INFO - 'MOZ_NO_REMOTE': '1', 08:21:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:21:22 INFO - 'NO_EM_RESTART': '1', 08:21:22 INFO - 'PAGER': '/bin/cat', 08:21:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:22 INFO - 'PWD': '/builds/slave/test', 08:21:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:21:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:22 INFO - 'RUST_BACKTRACE': '1', 08:21:22 INFO - 'SHELL': '/bin/bash', 08:21:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q8xgBeY6PP/Listeners', 08:21:22 INFO - 'TMPDIR': '/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/', 08:21:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:22 INFO - 'USER': 'cltbld', 08:21:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:22 INFO - 'XPC_FLAGS': '0x0', 08:21:22 INFO - 'XPC_SERVICE_NAME': '0', 08:21:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:22 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/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0285', '--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 08:21:22 INFO - mozversion application_buildid: 20170522065124 08:21:22 INFO - mozversion application_changeset: 8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f 08:21:22 INFO - mozversion application_display_name: Nightly 08:21:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:21:22 INFO - mozversion application_name: Firefox 08:21:22 INFO - mozversion application_remotingname: firefox 08:21:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:21:22 INFO - mozversion application_vendor: Mozilla 08:21:22 INFO - mozversion application_version: 55.0a1 08:21:22 INFO - mozversion platform_buildid: 20170522065124 08:21:22 INFO - mozversion platform_changeset: 8f4d2d35cb317235f30f4e3738ad3df16d2f9f3f 08:21:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:21:22 INFO - mozversion platform_version: 55.0a1 08:21:22 INFO - using testdate: 1495466482 08:21:22 INFO - actual date: 1495466482 08:21:22 INFO - starting webserver on 'localhost:49219' 08:21:22 INFO - SUITE-START | Running 2 tests 08:21:22 INFO - TEST-START | dromaeo_css 08:21:22 INFO - operating with platform_type : mac_ 08:21:22 INFO - Initialising browser for dromaeo_css test... 08:21:22 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpsuowca/profile http://localhost:49219/getInfo.html) 08:21:23 INFO - PID 1632 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 08:21:25 INFO - PID 1632 | 2017-05-22 08:21:25.314 plugin-container[1634:7540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 08:21:25 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:25 INFO - PID 1632 | 2017-05-22 08:21:25.321 plugin-container[1634:7540] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa10b, name = 'com.apple.CFPasteboardClient' 08:21:25 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:25 INFO - PID 1632 | 2017-05-22 08:21:25.321 plugin-container[1634:7540] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:25 INFO - PID 1632 | __metrics Screen width/height:1600/1200 08:21:25 INFO - PID 1632 | colorDepth:24 08:21:25 INFO - PID 1632 | Browser inner width/height: 1024/691 08:21:25 INFO - PID 1632 | __metrics 08:21:26 INFO - PID 1632 | 2017-05-22 08:21:26.020 plugin-container[1635:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x952f, name = 'com.apple.tsm.portname' 08:21:26 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:26 INFO - PID 1632 | 2017-05-22 08:21:26.021 plugin-container[1635:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 08:21:26 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:26 INFO - PID 1632 | 2017-05-22 08:21:26.021 plugin-container[1635:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:26 INFO - TEST-INFO | 1632: exit 0 08:21:26 INFO - Browser initialized. 08:21:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:21:26 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmpsuowca/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:28 INFO - PID 1636 | 2017-05-22 08:21:28.662 plugin-container[1637:7692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 08:21:28 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:28 INFO - PID 1636 | 2017-05-22 08:21:28.664 plugin-container[1637:7692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0f, name = 'com.apple.CFPasteboardClient' 08:21:28 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:28 INFO - PID 1636 | 2017-05-22 08:21:28.665 plugin-container[1637:7692] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:29 INFO - PID 1636 | 2017-05-22 08:21:29.346 plugin-container[1638:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x962b, name = 'com.apple.tsm.portname' 08:21:29 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:29 INFO - PID 1636 | 2017-05-22 08:21:29.346 plugin-container[1638:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 08:21:29 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:29 INFO - PID 1636 | 2017-05-22 08:21:29.346 plugin-container[1638:7737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:38 INFO - PID 1636 | RSS: Main: 240480256 08:21:38 INFO - PID 1636 | 08:24:42 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 08:24:43 INFO - PID 1636 | RSS: Main: 260423680 08:24:43 INFO - PID 1636 | 08:27:45 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 08:27:46 INFO - PID 1636 | RSS: Main: 274608128 08:27:46 INFO - PID 1636 | 08:30:47 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 08:30:48 INFO - PID 1636 | RSS: Main: 280027136 08:30:48 INFO - PID 1636 | 08:33:50 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 08:33:50 INFO - PID 1636 | RSS: Main: 277753856 08:33:50 INFO - PID 1636 | 08:36:53 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 08:36:54 INFO - PID 1636 | RSS: Main: 279511040 08:36:54 INFO - PID 1636 | 08:39:55 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 08:39:56 INFO - PID 1636 | RSS: Main: 282972160 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | __start_tp_report 08:39:56 INFO - PID 1636 | _x_x_mozilla_page_load 08:39:56 INFO - PID 1636 | _x_x_mozilla_page_load_details 08:39:56 INFO - PID 1636 | |i|pagename|runs| 08:39:56 INFO - PID 1636 | |0;dojo.html;3293.666065243577;3522.1018640246716;3560.430331147016;3617.04148400674;3628.5282913221286;6109.61722939893;6202.4417802397775;6203.782867599629;6215.968920155398;6226.844328891769;6149.200603921484;6150.784722534712;6188.938110618892;6228.034654628528;6254.812355629327;5345.946540534604;5413.188021796723;5427.592930530206;5432.6468779529205;5459;5465.863353416156;5522.061249587579;5534.640248383862;5565.693886836242;5585.692786896719;4385.144896745136;4478.373027776114;5482.5613950884235;5504.889902201973;5509.421510741375;5362.6782393056155;5448;5453.291072160594;5462.863428414282;5506.614536982413;5380.058489764276;5423.186522021689;5443.455654434564;5452.890942181134;5553.750081246354;6034.155218269446;6253.906191407133;6273.6235825850135;6273.749050037993;6274.843128921813;6444.323346048644;6528.347165283481;6538.051982462516;6601.108850305195;6642.634655093968;5839.737211825478;6818.761343352959;6839.965800170968;6841.486888483384;6850.109485766819;5096.745162741848;6344;6344.714487848058;6360.459360954278;6379.649119298437;6669.966650166718;6754.560953538103;6836.042953986446;6907.930920690829;6954.547954382949;5516.586256030814;6532.412082912466;6568.244651865001;6605.141331626858;6615.37153970372;6723.798286051427;6805.693743781541;6808.2510923798345;6809.5233333666165;6831.658417079127;5954.404559544011;6114.144019837227;6425.967870160619;6474.902876456857;6607.141071660653;6685.331466853276;6708.83227919306;6714.731410743613;6725.092112564741;6745.123133992648;5360.303160589098;5395.217693434509;5400.21696853954;5412.729363531807;5441.075017247077;4749.762511874393;4773.37946067009;4797.232442809134;4806.158922188673;4821.252705830602;6289.7484100635465;6314.210723659543;6314.336994615591;6316.557840951089;6322.146510221153;6736.427403670645;6783.253842077367;6801.353871382211;6822;6828.351306625863;6157.168782214344;6292.905606415908;6336.20797400325;6339.2709838368255;6365.2679941806355;5811.27359080115;5910.940890591125;5970.910436343458;5980.491658209101;5992.0712289595185;6866.038754574363;6892.551984121016;6938.7571435001555;6950.131233595801;6951.6871740770775;6707.027481015323;6760.256371799;6760.3239676032;6787.117674702257;6790.32096790317;6657.567258128206;6740.427063699641;6874.931250687429;6961.547499412522;6999.020137180901;6071.210742603434;6604.207495100619;6627.403533681992;6633.402993730588;6639.402453779183;6081.6620343524355;6738.831529211809;6799;6804.4556435484055;6823.771721090251;6146.293176284785;6585.80242592723;6592.571482853599;6596.472282217315;6609.537332386687;6363.172787537591;6408.359164083555;6467.902981455188;6485.805425837232;6512.185976752906;5371.785128594813;6114.692773002257;6358.618482891041;6413.326600706859;6453.612783233021;4832.589229915497;6487.253965793994;6503.869922601427;6555.737770489127;6574.145361103026;6204.906926396198;6428.292887782247;6431;6438.742450301936;6439.8068057958335 08:39:56 INFO - PID 1636 | |1;ext.html;682.6143229075572;709.2092317066471;827.6523859978809;1223.528941357577;1291.2639795316672;38210.85367438978;38598.4210236846;38614.8415547534;38706.41940370897;38783.61216387839;39326.80336598313;39352.62265820697;39652;39656.405153922715;39677.60322396775;7497.837835135562;7706.921031055656;8085.3531717462465;8274.2966847818;8279.793005174859;7612.961935190344;8455.661773529051;8492.872606910902;8514.191151840596;8517.318614510825;8425.578721063954;8450.450720703135;8523.786905327386;8530.104339044405;8533.871991920127;8490.745277641681;8493.405461617689;8494.532800695959;8497.980242370955;8524.190201930807;8211.425200235986;8227.83544329116;8270;8285;8286.337093032544;36717.63282367169;36769.816150919345;36783;36816;36848.44727329092;3593.82030898454;3602.9099272518133;3631.400818864884;3638.909027274313;3640.2355505343908;30294;30416.391672166654;30543.93096241643;30642.846785765927;30654;3077.984265192481;3109.580206672093;3110.8133511989354;3117.127204382754;3123.593932788723;249577;252275;279049.60475197696;307133.46433267917;307633;47469.05061898812;47740.283895741595;47814;47815.521844781804;47916.5208347919;49374.025038998756;49471.50528494741;49523.50476495189;49642;49758;14116;14198.219097949712;14276.500322488662;14295.57113286603;14297.85702142986;11447.484863181176;11535.826962595567;11551.595694150832;11556.711082223012;11572.322013308023;10742.78514429707;13475.326233688276;13495.122817017058;13524.256165910969;13576.796348054788;6045.788397406158;6075.635461872302;6106.328303886569;6115.1744514490865;6119.837230926199;1011.2365164300905;1021.8416145497459;1028.6039874648166;1059.6450189186555;1061.6125114333367;19000;22249;22722.659160112613;24075.036998519863;24171.15400960993;1114.7714718482853;1124.8087825069758;1130.8925652063042;1164.173436859822;1168.199783148546;11986.981106606036;12222.20555663879;12260.509579616893;12281.263124212575;12328.137030407965;306903;312583;312737;313947.86052139185;329993;55343.16985245224;55721;55963.720181398836;55994.880102398536;56103.71948140233;1463.7365274250737;1503.1131632337192;1504.2178067404889;1506.495324066428;1516.4161797707961;27573.724262757114;29426;30225.546616801206;30358.848205759714;30536.541951870295;37628.435573465875;48750;49777.751111244215;49853;50051.499485004686;242326;295761;297135;297168;297633;29811;30288.848555757962;33315.167120822174;35432.82283588566;35828.82085589555;34817.12957176091;41536.37695434631;42041.369379459946;43564;43898;29449.558256626595;30115.54826677558;30257.848710756305;30333.544996824625;30717.078487645405;27056.594151088142;28927.276818078873;29587.704122958494;30298.091057268317;30654.693453065185;31554.05574303212;33834.66165338315;36174.4573831398;36357.546368045005;36522.452163218106;28702;29923.55114673325;30063;30663.40048992669;30778;21768.45578860541;23791.167309144097;23907.402314942265;24369.51260974735;24379.805817476514 08:39:56 INFO - PID 1636 | |2;jquery.html;431.0839466134464;437.8489421149703;440.22300639371497;455.8450126956834;458.8738097023318;17049.488515344543;19544.804551954476;21796.564068718657;22038;22040.33878983631;21843.235486757967;21972.670409943865;22062;22229.2219772308;22242.555148897012;5978.9701051494685;6132.325444201134;6247.406496382859;6255.342334281419;6257.593256438339;5979.431953964388;5980.6411615303;6099.298580663236;6135.693215339237;6148.262208534959;5909.881802363951;6078.848028799293;6114.571979961404;6114.602550834182;6133.785317513888;5957.672328021957;6044.395560443986;6087.54343424245;6109.4501494865235;6125.5712100153005;5380.973095134499;5384.111621582434;5415.972920135414;5442.374126975369;5454.836354909359;26993;27694.584581231094;27804.58293125605;28048;28144.577831332546;2144.9034793434175;2201.0315461197024;2208.723909511311;2219.5671843990426;2221.48905751678;15490.845091549052;16781.496555103367;17623.383181588582;18086.457406277703;18466.63066738671;2123.7132987046707;2147.3987283560627;2171.0338899189865;2173.532690471556;2175.2060497918305;160823;161038;162965;164002;164600.3539964597;11606.88393116058;11636.010939070104;12550.372481376076;12707.428165732565;12879.09846310768;16456;16511.33954641825;16551.337946482246;16580.336786528645;16599.253033613517;8590.785230369165;8885.333599979898;8924.152205540464;8932.285417166611;9000.774980625536;8304.667813287522;8396.412251142483;8407.369447291478;8567.914320856711;8581.35639827003;6658.43403310724;6716.597004179765;7161.9641901790155;7984.520928744294;8074.717384891501;1826.844718198968;2137.5831712815852;2161.6973623692697;2162.7945345192184;2329.2895666821782;971.50453268832;986.1420564109271;986.6694657289745;994.3039872089566;1024.288119756762;21060.473488162614;21136.577268454854;21277.89361053216;21285;21290.467738306357;871.930245580352;989.9901000989898;999.3054826895238;1000.9399436033862;1030.0523518363125;8595.312375009984;9518.524073796421;10536.789264214673;10885.782284354269;11012.39431831241;454252;521944.17083743773;527775;538164;540164;122748;129829;134091.32954335166;136450;138511;1195.7668254690252;1221.395409272416;1247.444887025265;1248.2635245205372;1248.2759999200623;21908;23023.84880755922;23127.537449251256;23308.184213553122;23431.41421464409;45297.54702452933;53011.20483192678;56329.59176020468;56896.29311120673;57074.714626426605;76976.61511692406;78154;78219.04452388508;78634.6068269655;79138.6043069781;8887.244584210155;8891.466512009041;8915.331350148635;9152.450852948577;9195;14362.353694083582;14580.781288280896;14697.70604587881;14786.408543658563;14873.107613543221;10770.892291077302;10949.288296260718;11005.504752286006;11751.412429378668;12100.636980890587;10187.847182292124;11681.824772628588;11683.299002059903;11802.704932376759;12055.397230138633;11991.280523168638;12118.878811212127;12213.206141601124;12219.87780122223;12410;7677.309042186007;9096.363254572116;9099.54502274897;9154.489509231169;9250.629974800933;11895.286282822712;11941.940290298493;12115.57595484189;12278.693032673897;12287.815682764587 08:39:56 INFO - PID 1636 | |3;mootools.html;1246.4577908609756;1435.0456946130823;1440.5030264558727;1442.0986883198002;1455.4178328668531;11262.267952583077;11435.771284574303;11939.16425850192;11947.163698541095;12092.758144837098;11627;11740.94129529351;11873.584424545143;11934.104942129332;11941.58204462844;2555.8466492010493;2565.320190149612;2587.676540432446;2592.7925765938776;2599.5052844614424;2526.1411120219122;2553.3871870750977;2583.2895953612815;2585.121058839994;2586.6378706981036;2510.3598582361474;2525.722170561246;2556.6804149481313;2560.6159076138633;2561.0140096063055;2507.448361360507;2529.6584961030303;2558.1941688368292;2565.28172111809;2569.486102779452;2368.14746691191;2525.623535173335;2548.210054882992;2559.897604095834;2562.461883004571;9691.418514889128;11465.713357166054;11585.18903676743;11607.187496875222;11640.126990475663;6525.673716314165;6680.098186744776;6746.662666866686;6748.527603067787;6757.256701762803;3944.2703099926607;4274.487061552602;4313.137372525507;4342.630876375511;4350.912981740379;1220.9084318676046;1235.962921112359;1258.1004581724019;1264.374134803269;1272.872712728729;14118.647033824134;15302.617434564114;16025.91987040069;16134.515964521082;16248.75626865598;5384.811531596375;5426.294581704353;5530.391656917735;5669.603127781016;5686.289213848269;11602.825957610643;11739;11766.647000589996;11769.52921883115;11780.646580602595;6416;6435.871282574322;6457.3865482779065;6461.515386346043;6480.092787009822;5993.550483713739;6084.5436592255755;6170.352113028157;6186.350433204539;6203.4416902479;3743.2513497300647;3762.435634654769;4215.6205941464805;4373.650107991353;4426.22541055314;1289.9097063205486;1294.779887419141;1298.740251949595;1305.4647594486214;1309.9934500327438;698.4168219536743;781.9335356494648;789.740364119229;806.2783808491367;811.8822770698334;6103.908441373383;6179.474744646755;6181.907271390933;6238.968805155945;6279.843003924939;517.0615333170284;582.9387914269025;584.7193347193371;612.1460562515266;618.0667192539208;3103.6275646922513;3575.6781889630056;4167.895802604899;4303.332983387518;4566.0411313623845;14653.853461465462;14711.852881471263;14785.556433307018;14891;14903.105813651217;14296.356663950148;14503.347349369093;14747.926260368844;14915.925420372829;14951.177685227416;8341.582920853933;8567.700130495403;8583.356248281403;8596.828063438696;8597.441166324168;6856.24581296067;6960.164780226202;7003.54476958996;7035.788926332219;7072.469564782559;11646.825297620711;12860.807087893501;13241;13373.933130334286;13410.12834165776;13361.398737056661;13549;13605.183688978694;13687.657808554866;13759.79360309576;8159.26566609008;8178.304844088319;8236.752897413087;8257.174282571696;8257.669693212445;10534.52594633228;10770.946145269538;10772.192085593768;10779.568817247537;10793.892061079603;8920.018797932353;8996.640134394813;9040.457572545669;9159.542022898695;9294.349395542247;8784;8844.911550884666;8875.467471951704;8951.791508146316;9018.413803102778;8158.1026087129185;8203.917960820316;8228.177182281725;8323.750287491384;8329;5117.667351622133;5395;5986.610870293417;6075.969620151871;6373;6116;6183.81448556544;6206.844828879314;6227.719752611053;6285.340039295808 08:39:56 INFO - PID 1636 | |4;prototype.html;327.8246138316;384.1452767592108;396.3539430727913;398.1499498570551;405.5174342532386;6276.466500347465;6287.559870809046;6309.085182648513;6334.746610135595;6335.904961425582;4454.26504626737;6102.474381404649;6300.338464461234;6301.873962520747;6303.527235457347;3972.2651309507687;4397.802098905557;4454.933176002362;4569.6115830154395;4578.587927086562;3840.3087444260022;4497.730136191839;4585.151746926829;4606.286025666027;4607.193741095311;4537.773111344436;4552.567506086916;4560.794764235618;4561.6806823522365;4590.1278757036225;3709.1654377765058;4082.8775136745944;4111.506619205715;4546.158960592301;4574.153781550407;3226.677332266778;3695.371786796224;3881.4177873318945;3894.2990261752875;3916.5104361954754;6115.021596544576;6181;6194.783182588588;6212.9068063979075;6227.501799856001;4483.887902802423;4818.397700287464;5203.881165549425;5248.396434410053;5481.616286859921;4910.607151427913;6747.93252067478;6749.493787965873;6764.558985116382;6767.154105736783;4294.26997410441;4420.31485119807;4420.541221396931;4428.424304840382;4431.8448854289945;6114.654775949284;6955.339242771928;7136.964315178391;7163.7492687755675;7253.419726421874;4433;4465.151621191963;4469.374287599739;4469.396631454777;4473.843415480443;3608.8376023079027;4143.948745371001;4414.448193975753;4548.545145485491;4561.270196768567;5319.286904298652;5390.51485366319;5424.159255315432;5428.2671839301975;5472.370677372073;5347.652402593897;6200.627962322275;6752.189737231465;6762;6767.898481522781;1529;1542.5141080559588;1551.8215405228543;1556.2218890554723;1556.486359501362;821.6483884010769;978.9755256118511;1004.4180415845074;1012.3520946594185;1021.2391768132788;5566.387697353288;6327.3988971047675;6343.492520598434;6352.936470635327;6371.33101024395;5818.301803783573;6103.42017508336;6436.259830119596;6772.462450158406;7005.369516743415;5051.82318618854;5357.785688572491;6243.157173781572;6285.025820997753;6355.0785136155;2921.985390073036;2940.7941444099138;3516.9472457913153;3566.054995426214;3631.1829838286176;5725.4274572543245;7045.295470452914;7122.643867806589;7159.821004474826;7163.247858974837;6007.909881351783;6616.86766264672;7085.397741191953;7196.784096477009;7205.351518363373;6216.906746398718;6538.1827271591055;6907.792766217021;7081.575105493687;7205.747798827121;6958.79123626292;6960.79117626472;6962.512624116466;6970.79087627372;7002.404795592432;6125.387461253839;6487.448566871869;6781.321867813179;6850.38346548813;6871.9656401717675;6918;6980.568983358297;6996.335348141918;7022.683979220845;7171.49799514029;3728.8694895678523;3954.4859168307935;4199.661269290982;4292.8838501989085;4457.643388528974;5579.442055794388;6097;6796.558223715443;7021.1574611047;7031.296870312928;5940.405959404198;6712.228093769279;6752.919532874716;6768.932310677027;6773.830654233487;6010.3088144863905;6134.8159555213415;6683.9331606685255;6748.493862960199;6754.223264324469;5421.484958928895;6115.510759139169;6705.262421133769;6709.4632429404555;6733.494987876027;5983;6367.840803979938;6569.540132190701;6799.490038247052;6877;5393.411162641875;5946;6460.353964603502;6645.867082658417;6669.6331701754925 08:39:56 INFO - PID 1636 | |5;yui.html;809.7125520440239;820.8809722590229;827.1356432528007;846.7764080903097;849.5115308697499;15762.133082680448;16143.273552690127;16171.353145874165;18932.432027039176;19063.428097157073;18005.21890403014;18054.638907221848;18086.638267234648;18371.44885653429;18682.906585467055;976.0971101730906;985.374287327545;998.6454808542935;1001.4091685905314;1004.2618675273668;988.5057471264367;1003.4781913405025;1003.8996100389976;1005.8088963096989;1007.385494848142;1058.6083149234794;1058.9629362972298;1060.3796778884323;1062.0812996757827;1064.4464878263298;865.3942240431692;1052.63157894737;1054.5939813171947;1055.6094245129314;1055.8205105132147;741.9814504637374;749.6963729689485;754.8188434775669;763.9197884222187;772.7102336623766;16450.012999220085;16527.669446611122;16650.250738716786;16677.916610416993;16686.58283542909;16526.66946661072;16576.502704918752;16963.23665435046;17083.658326833392;17116.914415427967;14015.948803839752;14030.508932187426;14215.223097112861;14279.35742891562;14298.428062877472;3466.9133271668156;3530.91172720684;3535.939218234545;3536.469529570559;3541.5218945442552;117452.41273793577;133870.6612933868;134373.3281333597;134787;136569.26861462815;11841.28952262867;13401.66495837612;13409.530666426628;13412.12821166621;13562.796558051637;25540.74459255421;25554.48891022169;25913.61129583058;26032.73967260341;26334;7573.2805383488485;7883;7970.4819186752675;8009.799755006172;8035.196480351918;3956.5449973252867;4004.2592120457807;4005.0588111793704;4012.7191096622955;4068.16602596467;4295.935560966588;4304.246756817544;4324.0919406924895;4380.737155770664;4398.252297109499;931.3806318797923;949.9097585729482;954.8090381923643;954.9236061115095;956.1442508954447;647.9546431749732;664.7540410048313;669.6450881033061;680.2143524229523;688.4664385101486;10482.37105773656;10941.507632156572;11927.582534611247;11962.760744785055;12075.879241207651;630.3224034163293;658.2232965101226;666.7033170239293;670.9597424154566;676.5129107042922;4664.370310008106;5420.83737487876;5541.667499950016;5562.888742225214;5735.541156707453;103806;107161.39257911137;107168;107441.46279268553;108580;63038.68480657567;88266;90660.73348166348;91000.54499727458;91279.5436022829;56901;74761.38335158244;81075.18924810676;82592.5018622866;83560.58219708863;10483.737906552396;10638.042576168182;11450.370229637118;11457.083433325148;11593.362365070003;34902.60389584388;39630.216640251834;40879.59120408758;41256.381154282106;41364.586354137275;53726.73136634448;54181;54661.72669136629;55301.72349138228;55612.05357812457;14132.858671413154;14163.929180354444;14230.430782768573;14417.279136043366;14478.493252736103;15155.393784248507;15486.148261845256;15538.61153471127;15718.685626287639;15726.449574264845;14467.421303147756;14566.781498277322;14603.415863365772;14826.851731482548;14888.181150036851;11802.291862488279;13498.190108593515;13903.374348154559;14433.278336083364;14435.639109022359;11624.535018599161;14072.366743496772;14152.363143658355;14153.929230353782;14258.928705356822;10552.683419497427;10615.1507879371;10920.563177472812;10926.890731092906;11226.045786107945;11676.299422034705;11799.115066370228;11837.230580012272;11866.169368144147;12030.75938481243 08:39:56 INFO - PID 1636 | __end_tp_report 08:39:56 INFO - PID 1636 | __start_cc_report 08:39:56 INFO - PID 1636 | _x_x_mozilla_cycle_collect,339 08:39:56 INFO - PID 1636 | __end_cc_report 08:39:56 INFO - PID 1636 | __startTimestamp1495467596215__endTimestamp 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | ------- Summary: start ------- 08:39:56 INFO - PID 1636 | Number of tests: 6 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6129.13 Median:6344.71 stddev:750.21 (11.8%) stddev-sans-first:718.78 08:39:56 INFO - PID 1636 | Values: 3293.7 3522.1 3560.4 3617.0 3628.5 6109.6 6202.4 6203.8 6216.0 6226.8 6149.2 6150.8 6188.9 6228.0 6254.8 5345.9 5413.2 5427.6 5432.6 5459.0 5465.9 5522.1 5534.6 5565.7 5585.7 4385.1 4478.4 5482.6 5504.9 5509.4 5362.7 5448.0 5453.3 5462.9 5506.6 5380.1 5423.2 5443.5 5452.9 5553.8 6034.2 6253.9 6273.6 6273.7 6274.8 6444.3 6528.3 6538.1 6601.1 6642.6 5839.7 6818.8 6840.0 6841.5 6850.1 5096.7 6344.0 6344.7 6360.5 6379.6 6670.0 6754.6 6836.0 6907.9 6954.5 5516.6 6532.4 6568.2 6605.1 6615.4 6723.8 6805.7 6808.3 6809.5 6831.7 5954.4 6114.1 6426.0 6474.9 6607.1 6685.3 6708.8 6714.7 6725.1 6745.1 5360.3 5395.2 5400.2 5412.7 5441.1 4749.8 4773.4 4797.2 4806.2 4821.3 6289.7 6314.2 6314.3 6316.6 6322.1 6736.4 6783.3 6801.4 6822.0 6828.4 6157.2 6292.9 6336.2 6339.3 6365.3 5811.3 5910.9 5970.9 5980.5 5992.1 6866.0 6892.6 6938.8 6950.1 6951.7 6707.0 6760.3 6760.3 6787.1 6790.3 6657.6 6740.4 6874.9 6961.5 6999.0 6071.2 6604.2 6627.4 6633.4 6639.4 6081.7 6738.8 6799.0 6804.5 6823.8 6146.3 6585.8 6592.6 6596.5 6609.5 6363.2 6408.4 6467.9 6485.8 6512.2 5371.8 6114.7 6358.6 6413.3 6453.6 4832.6 6487.3 6503.9 6555.7 6574.1 6204.9 6428.3 6431.0 6438.7 6439.8 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:44729.66 Median:312660.00 stddev:77202.79 (24.7%) stddev-sans-first:77348.53 08:39:56 INFO - PID 1636 | Values: 682.6 709.2 827.7 1223.5 1291.3 38210.9 38598.4 38614.8 38706.4 38783.6 39326.8 39352.6 39652.0 39656.4 39677.6 7497.8 7706.9 8085.4 8274.3 8279.8 7613.0 8455.7 8492.9 8514.2 8517.3 8425.6 8450.5 8523.8 8530.1 8533.9 8490.7 8493.4 8494.5 8498.0 8524.2 8211.4 8227.8 8270.0 8285.0 8286.3 36717.6 36769.8 36783.0 36816.0 36848.4 3593.8 3602.9 3631.4 3638.9 3640.2 30294.0 30416.4 30543.9 30642.8 30654.0 3078.0 3109.6 3110.8 3117.1 3123.6 249577.0 252275.0 279049.6 307133.5 307633.0 47469.1 47740.3 47814.0 47815.5 47916.5 49374.0 49471.5 49523.5 49642.0 49758.0 14116.0 14198.2 14276.5 14295.6 14297.9 11447.5 11535.8 11551.6 11556.7 11572.3 10742.8 13475.3 13495.1 13524.3 13576.8 6045.8 6075.6 6106.3 6115.2 6119.8 1011.2 1021.8 1028.6 1059.6 1061.6 19000.0 22249.0 22722.7 24075.0 24171.2 1114.8 1124.8 1130.9 1164.2 1168.2 11987.0 12222.2 12260.5 12281.3 12328.1 306903.0 312583.0 312737.0 313947.9 329993.0 55343.2 55721.0 55963.7 55994.9 56103.7 1463.7 1503.1 1504.2 1506.5 1516.4 27573.7 29426.0 30225.5 30358.8 30536.5 37628.4 48750.0 49777.8 49853.0 50051.5 242326.0 295761.0 297135.0 297168.0 297633.0 29811.0 30288.8 33315.2 35432.8 35828.8 34817.1 41536.4 42041.4 43564.0 43898.0 29449.6 30115.5 30257.8 30333.5 30717.1 27056.6 28927.3 29587.7 30298.1 30654.7 31554.1 33834.7 36174.5 36357.5 36522.5 28702.0 29923.6 30063.0 30663.4 30778.0 21768.5 23791.2 23907.4 24369.5 24379.8 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:34952.23 Median:22633.20 stddev:88692.38 (391.9%) stddev-sans-first:88903.31 08:39:56 INFO - PID 1636 | Values: 431.1 437.8 440.2 455.8 458.9 17049.5 19544.8 21796.6 22038.0 22040.3 21843.2 21972.7 22062.0 22229.2 22242.6 5979.0 6132.3 6247.4 6255.3 6257.6 5979.4 5980.6 6099.3 6135.7 6148.3 5909.9 6078.8 6114.6 6114.6 6133.8 5957.7 6044.4 6087.5 6109.5 6125.6 5381.0 5384.1 5416.0 5442.4 5454.8 26993.0 27694.6 27804.6 28048.0 28144.6 2144.9 2201.0 2208.7 2219.6 2221.5 15490.8 16781.5 17623.4 18086.5 18466.6 2123.7 2147.4 2171.0 2173.5 2175.2 160823.0 161038.0 162965.0 164002.0 164600.4 11606.9 11636.0 12550.4 12707.4 12879.1 16456.0 16511.3 16551.3 16580.3 16599.3 8590.8 8885.3 8924.2 8932.3 9000.8 8304.7 8396.4 8407.4 8567.9 8581.4 6658.4 6716.6 7162.0 7984.5 8074.7 1826.8 2137.6 2161.7 2162.8 2329.3 971.5 986.1 986.7 994.3 1024.3 21060.5 21136.6 21277.9 21285.0 21290.5 871.9 990.0 999.3 1000.9 1030.1 8595.3 9518.5 10536.8 10885.8 11012.4 454252.0 521944.2 527775.0 538164.0 540164.0 122748.0 129829.0 134091.3 136450.0 138511.0 1195.8 1221.4 1247.4 1248.3 1248.3 21908.0 23023.8 23127.5 23308.2 23431.4 45297.5 53011.2 56329.6 56896.3 57074.7 76976.6 78154.0 78219.0 78634.6 79138.6 8887.2 8891.5 8915.3 9152.5 9195.0 14362.4 14580.8 14697.7 14786.4 14873.1 10770.9 10949.3 11005.5 11751.4 12100.6 10187.8 11681.8 11683.3 11802.7 12055.4 11991.3 12118.9 12213.2 12219.9 12410.0 7677.3 9096.4 9099.5 9154.5 9250.6 11895.3 11941.9 12115.6 12278.7 12287.8 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7002.17 Median:3659.46 stddev:4403.15 (120.3%) stddev-sans-first:4394.26 08:39:56 INFO - PID 1636 | Values: 1246.5 1435.0 1440.5 1442.1 1455.4 11262.3 11435.8 11939.2 11947.2 12092.8 11627.0 11740.9 11873.6 11934.1 11941.6 2555.8 2565.3 2587.7 2592.8 2599.5 2526.1 2553.4 2583.3 2585.1 2586.6 2510.4 2525.7 2556.7 2560.6 2561.0 2507.4 2529.7 2558.2 2565.3 2569.5 2368.1 2525.6 2548.2 2559.9 2562.5 9691.4 11465.7 11585.2 11607.2 11640.1 6525.7 6680.1 6746.7 6748.5 6757.3 3944.3 4274.5 4313.1 4342.6 4350.9 1220.9 1236.0 1258.1 1264.4 1272.9 14118.6 15302.6 16025.9 16134.5 16248.8 5384.8 5426.3 5530.4 5669.6 5686.3 11602.8 11739.0 11766.6 11769.5 11780.6 6416.0 6435.9 6457.4 6461.5 6480.1 5993.6 6084.5 6170.4 6186.4 6203.4 3743.3 3762.4 4215.6 4373.7 4426.2 1289.9 1294.8 1298.7 1305.5 1310.0 698.4 781.9 789.7 806.3 811.9 6103.9 6179.5 6181.9 6239.0 6279.8 517.1 582.9 584.7 612.1 618.1 3103.6 3575.7 4167.9 4303.3 4566.0 14653.9 14711.9 14785.6 14891.0 14903.1 14296.4 14503.3 14747.9 14915.9 14951.2 8341.6 8567.7 8583.4 8596.8 8597.4 6856.2 6960.2 7003.5 7035.8 7072.5 11646.8 12860.8 13241.0 13373.9 13410.1 13361.4 13549.0 13605.2 13687.7 13759.8 8159.3 8178.3 8236.8 8257.2 8257.7 10534.5 10770.9 10772.2 10779.6 10793.9 8920.0 8996.6 9040.5 9159.5 9294.3 8784.0 8844.9 8875.5 8951.8 9018.4 8158.1 8203.9 8228.2 8323.8 8329.0 5117.7 5395.0 5986.6 6076.0 6373.0 6116.0 6183.8 6206.8 6227.7 6285.3 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5318.70 Median:6102.95 stddev:1732.02 (28.4%) stddev-sans-first:1695.89 08:39:56 INFO - PID 1636 | Values: 327.8 384.1 396.4 398.1 405.5 6276.5 6287.6 6309.1 6334.7 6335.9 4454.3 6102.5 6300.3 6301.9 6303.5 3972.3 4397.8 4454.9 4569.6 4578.6 3840.3 4497.7 4585.2 4606.3 4607.2 4537.8 4552.6 4560.8 4561.7 4590.1 3709.2 4082.9 4111.5 4546.2 4574.2 3226.7 3695.4 3881.4 3894.3 3916.5 6115.0 6181.0 6194.8 6212.9 6227.5 4483.9 4818.4 5203.9 5248.4 5481.6 4910.6 6747.9 6749.5 6764.6 6767.2 4294.3 4420.3 4420.5 4428.4 4431.8 6114.7 6955.3 7137.0 7163.7 7253.4 4433.0 4465.2 4469.4 4469.4 4473.8 3608.8 4143.9 4414.4 4548.5 4561.3 5319.3 5390.5 5424.2 5428.3 5472.4 5347.7 6200.6 6752.2 6762.0 6767.9 1529.0 1542.5 1551.8 1556.2 1556.5 821.6 979.0 1004.4 1012.4 1021.2 5566.4 6327.4 6343.5 6352.9 6371.3 5818.3 6103.4 6436.3 6772.5 7005.4 5051.8 5357.8 6243.2 6285.0 6355.1 2922.0 2940.8 3516.9 3566.1 3631.2 5725.4 7045.3 7122.6 7159.8 7163.2 6007.9 6616.9 7085.4 7196.8 7205.4 6216.9 6538.2 6907.8 7081.6 7205.7 6958.8 6960.8 6962.5 6970.8 7002.4 6125.4 6487.4 6781.3 6850.4 6872.0 6918.0 6980.6 6996.3 7022.7 7171.5 3728.9 3954.5 4199.7 4292.9 4457.6 5579.4 6097.0 6796.6 7021.2 7031.3 5940.4 6712.2 6752.9 6768.9 6773.8 6010.3 6134.8 6683.9 6748.5 6754.2 5421.5 6115.5 6705.3 6709.5 6733.5 5983.0 6367.8 6569.5 6799.5 6877.0 5393.4 5946.0 6460.4 6645.9 6669.6 08:39:56 INFO - PID 1636 | 08:39:56 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21260.54 Median:17023.45 stddev:30870.34 (181.3%) stddev-sans-first:30918.75 08:39:56 INFO - PID 1636 | Values: 809.7 820.9 827.1 846.8 849.5 15762.1 16143.3 16171.4 18932.4 19063.4 18005.2 18054.6 18086.6 18371.4 18682.9 976.1 985.4 998.6 1001.4 1004.3 988.5 1003.5 1003.9 1005.8 1007.4 1058.6 1059.0 1060.4 1062.1 1064.4 865.4 1052.6 1054.6 1055.6 1055.8 742.0 749.7 754.8 763.9 772.7 16450.0 16527.7 16650.3 16677.9 16686.6 16526.7 16576.5 16963.2 17083.7 17116.9 14015.9 14030.5 14215.2 14279.4 14298.4 3466.9 3530.9 3535.9 3536.5 3541.5 117452.4 133870.7 134373.3 134787.0 136569.3 11841.3 13401.7 13409.5 13412.1 13562.8 25540.7 25554.5 25913.6 26032.7 26334.0 7573.3 7883.0 7970.5 8009.8 8035.2 3956.5 4004.3 4005.1 4012.7 4068.2 4295.9 4304.2 4324.1 4380.7 4398.3 931.4 949.9 954.8 954.9 956.1 648.0 664.8 669.6 680.2 688.5 10482.4 10941.5 11927.6 11962.8 12075.9 630.3 658.2 666.7 671.0 676.5 4664.4 5420.8 5541.7 5562.9 5735.5 103806.0 107161.4 107168.0 107441.5 108580.0 63038.7 88266.0 90660.7 91000.5 91279.5 56901.0 74761.4 81075.2 82592.5 83560.6 10483.7 10638.0 11450.4 11457.1 11593.4 34902.6 39630.2 40879.6 41256.4 41364.6 53726.7 54181.0 54661.7 55301.7 55612.1 14132.9 14163.9 14230.4 14417.3 14478.5 15155.4 15486.1 15538.6 15718.7 15726.4 14467.4 14566.8 14603.4 14826.9 14888.2 11802.3 13498.2 13903.4 14433.3 14435.6 11624.5 14072.4 14152.4 14153.9 14258.9 10552.7 10615.2 10920.6 10926.9 11226.0 11676.3 11799.1 11837.2 11866.2 12030.8 08:39:56 INFO - PID 1636 | -------- Summary: end -------- 08:39:56 INFO - PID 1636 | 08:39:56 INFO - TEST-INFO | 1636: exit 0 08:39:56 INFO - TEST-OK | dromaeo_css | took 1114370ms 08:39:56 INFO - TEST-START | kraken 08:39:56 INFO - operating with platform_type : mac_ 08:39:56 INFO - Initialising browser for kraken test... 08:39:56 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmph3Tm_c/profile http://localhost:49219/getInfo.html) 08:39:57 INFO - PID 1651 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 08:39:59 INFO - PID 1651 | 2017-05-22 08:39:59.396 plugin-container[1652:10085] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 08:39:59 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:59 INFO - PID 1651 | 2017-05-22 08:39:59.399 plugin-container[1652:10085] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 08:39:59 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:59 INFO - PID 1651 | 2017-05-22 08:39:59.399 plugin-container[1652:10085] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:39:59 INFO - PID 1651 | __metrics Screen width/height:1600/1200 08:39:59 INFO - PID 1651 | colorDepth:24 08:39:59 INFO - PID 1651 | Browser inner width/height: 1024/691 08:39:59 INFO - PID 1651 | __metrics 08:40:00 INFO - TEST-INFO | 1651: exit 0 08:40:00 INFO - Browser initialized. 08:40:00 INFO - Running cycle 1/1 for kraken test... 08:40:00 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/T/tmph3Tm_c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:02 INFO - PID 1654 | 2017-05-22 08:40:02.253 plugin-container[1655:10229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 08:40:02 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:02 INFO - PID 1654 | 2017-05-22 08:40:02.257 plugin-container[1655:10229] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 08:40:02 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:02 INFO - PID 1654 | 2017-05-22 08:40:02.257 plugin-container[1655:10229] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:40:03 INFO - PID 1654 | 2017-05-22 08:40:03.033 plugin-container[1656:10281] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 08:40:03 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:03 INFO - PID 1654 | 2017-05-22 08:40:03.034 plugin-container[1656:10281] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 08:40:03 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:40:03 INFO - PID 1654 | 2017-05-22 08:40:03.034 plugin-container[1656:10281] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:40:12 INFO - PID 1654 | RSS: Main: 242577408 08:40:12 INFO - PID 1654 | 08:40:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 08:40:15 INFO - PID 1654 | RSS: Main: 254251008 08:40:15 INFO - PID 1654 | 08:40:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 08:40:19 INFO - PID 1654 | RSS: Main: 263340032 08:40:19 INFO - PID 1654 | 08:40:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 08:40:23 INFO - PID 1654 | RSS: Main: 265207808 08:40:23 INFO - PID 1654 | 08:40:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 08:40:25 INFO - PID 1654 | RSS: Main: 267227136 08:40:25 INFO - PID 1654 | 08:40:27 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:40:28 INFO - PID 1654 | RSS: Main: 267935744 08:40:28 INFO - PID 1654 | 08:40:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 08:40:31 INFO - PID 1654 | RSS: Main: 277655552 08:40:31 INFO - PID 1654 | 08:40:34 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 08:40:35 INFO - PID 1654 | RSS: Main: 277770240 08:40:35 INFO - PID 1654 | 08:40:38 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 08:40:39 INFO - PID 1654 | RSS: Main: 276692992 08:40:39 INFO - PID 1654 | 08:40:40 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:40:41 INFO - PID 1654 | RSS: Main: 275861504 08:40:41 INFO - PID 1654 | 08:40:43 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:40:43 INFO - PID 1654 | RSS: Main: 275804160 08:40:43 INFO - PID 1654 | 08:40:45 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:40:46 INFO - PID 1654 | RSS: Main: 275349504 08:40:46 INFO - PID 1654 | 08:40:49 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:40:50 INFO - PID 1654 | RSS: Main: 275976192 08:40:50 INFO - PID 1654 | 08:40:53 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:40:53 INFO - PID 1654 | RSS: Main: 275734528 08:40:53 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 08:40:55 INFO - PID 1654 | RSS: Main: 277737472 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | __start_tp_report 08:40:55 INFO - PID 1654 | _x_x_mozilla_page_load 08:40:55 INFO - PID 1654 | _x_x_mozilla_page_load_details 08:40:55 INFO - PID 1654 | |i|pagename|runs| 08:40:55 INFO - PID 1654 | |0;ai-astar;101;114;116;114;113;116;115;114;112;114 08:40:55 INFO - PID 1654 | |1;audio-beat-detection;181;132;114;155;114;115;120;117;141;112 08:40:55 INFO - PID 1654 | |2;audio-dft;203;194;228;228;204;214;204;196;199;223 08:40:55 INFO - PID 1654 | |3;audio-fft;80;62;63;84;67;72;51;52;76;48 08:40:55 INFO - PID 1654 | |4;audio-oscillator;72;70;111;104;84;69;85;74;71;91 08:40:55 INFO - PID 1654 | |5;imaging-gaussian-blur;106;90;91;97;91;90;90;88;90;89 08:40:55 INFO - PID 1654 | |6;imaging-darkroom;97;98;96;97;98;97;97;98;103;100 08:40:55 INFO - PID 1654 | |7;imaging-desaturate;105;140;146;91;90;92;92;110;112;89 08:40:55 INFO - PID 1654 | |8;json-parse-financial;48;51;50;51;50;52;52;52;51;52 08:40:55 INFO - PID 1654 | |9;json-stringify-tinderbox;56;62;84;95;52;59;78;85;83;86 08:40:55 INFO - PID 1654 | |10;stanford-crypto-aes;80;83;83;84;84;82;93;79;110;110 08:40:55 INFO - PID 1654 | |11;stanford-crypto-ccm;140;140;145;137;152;147;138;140;196;138 08:40:55 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;185;191;187;186;183;188;186;186;188;191 08:40:55 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;71;70;67;70;70;69;68;69;70;71 08:40:55 INFO - PID 1654 | __end_tp_report 08:40:55 INFO - PID 1654 | __start_cc_report 08:40:55 INFO - PID 1654 | _x_x_mozilla_cycle_collect,923 08:40:55 INFO - PID 1654 | __end_cc_report 08:40:55 INFO - PID 1654 | __startTimestamp1495467655740__endTimestamp 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | ------- Summary: start ------- 08:40:55 INFO - PID 1654 | Number of tests: 14 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:4.36 (3.8%) stddev-sans-first:1.30 08:40:55 INFO - PID 1654 | Values: 101.0 114.0 116.0 114.0 113.0 116.0 115.0 114.0 112.0 114.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:126.00 stddev:22.81 (18.1%) stddev-sans-first:15.01 08:40:55 INFO - PID 1654 | Values: 181.0 132.0 114.0 155.0 114.0 115.0 120.0 117.0 141.0 112.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:209.30 Median:209.00 stddev:13.00 (6.2%) stddev-sans-first:13.59 08:40:55 INFO - PID 1654 | Values: 203.0 194.0 228.0 228.0 204.0 214.0 204.0 196.0 199.0 223.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:65.50 Median:69.50 stddev:12.58 (18.1%) stddev-sans-first:12.20 08:40:55 INFO - PID 1654 | Values: 80.0 62.0 63.0 84.0 67.0 72.0 51.0 52.0 76.0 48.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:73.00 stddev:14.93 (20.4%) stddev-sans-first:15.28 08:40:55 INFO - PID 1654 | Values: 72.0 70.0 111.0 104.0 84.0 69.0 85.0 74.0 71.0 91.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:90.00 stddev:5.41 (6.0%) stddev-sans-first:2.55 08:40:55 INFO - PID 1654 | Values: 106.0 90.0 91.0 97.0 91.0 90.0 90.0 88.0 90.0 89.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:97.00 stddev:2.02 (2.1%) stddev-sans-first:2.11 08:40:55 INFO - PID 1654 | Values: 97.0 98.0 96.0 97.0 98.0 97.0 97.0 98.0 103.0 100.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:106.70 Median:89.50 stddev:20.99 (23.5%) stddev-sans-first:22.26 08:40:55 INFO - PID 1654 | Values: 105.0 140.0 146.0 91.0 90.0 92.0 92.0 110.0 112.0 89.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.50 stddev:1.29 (2.5%) stddev-sans-first:0.83 08:40:55 INFO - PID 1654 | Values: 48.0 51.0 50.0 51.0 50.0 52.0 52.0 52.0 51.0 52.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:74.00 Median:83.50 stddev:15.20 (18.2%) stddev-sans-first:14.66 08:40:55 INFO - PID 1654 | Values: 56.0 62.0 84.0 95.0 52.0 59.0 78.0 85.0 83.0 86.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:83.00 stddev:11.78 (14.2%) stddev-sans-first:12.06 08:40:55 INFO - PID 1654 | Values: 80.0 83.0 83.0 84.0 84.0 82.0 93.0 79.0 110.0 110.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:142.50 stddev:17.76 (12.5%) stddev-sans-first:18.64 08:40:55 INFO - PID 1654 | Values: 140.0 140.0 145.0 137.0 152.0 147.0 138.0 140.0 196.0 138.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.10 Median:187.50 stddev:2.51 (1.3%) stddev-sans-first:2.55 08:40:55 INFO - PID 1654 | Values: 185.0 191.0 187.0 186.0 183.0 188.0 186.0 186.0 188.0 191.0 08:40:55 INFO - PID 1654 | 08:40:55 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.27 (1.8%) stddev-sans-first:1.22 08:40:55 INFO - PID 1654 | Values: 71.0 70.0 67.0 70.0 70.0 69.0 68.0 69.0 70.0 71.0 08:40:55 INFO - PID 1654 | -------- Summary: end -------- 08:40:55 INFO - PID 1654 | 08:40:56 INFO - TEST-INFO | 1654: exit 0 08:40:56 INFO - TEST-OK | kraken | took 59723ms 08:40:56 INFO - SUITE-END | took 1174s 08:40:56 INFO - Completed test suite (00:19:34) 08:40:56 INFO - javascript benchmark 08:40:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7819.323263539026, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.666065243577, 3522.1018640246716, 3560.430331147016, 3617.04148400674, 3628.5282913221286, 6109.61722939893, 6202.4417802397775, 6203.782867599629, 6215.968920155398, 6226.844328891769, 6149.200603921484, 6150.784722534712, 6188.938110618892, 6228.034654628528, 6254.812355629327, 5345.946540534604, 5413.188021796723, 5427.592930530206, 5432.6468779529205, 5459.0, 5465.863353416156, 5522.061249587579, 5534.640248383862, 5565.693886836242, 5585.692786896719, 4385.144896745136, 4478.373027776114, 5482.5613950884235, 5504.889902201973, 5509.421510741375, 5362.6782393056155, 5448.0, 5453.291072160594, 5462.863428414282, 5506.614536982413, 5380.058489764276, 5423.186522021689, 5443.455654434564, 5452.890942181134, 5553.750081246354, 6034.155218269446, 6253.906191407133, 6273.6235825850135, 6273.749050037993, 6274.843128921813, 6444.323346048644, 6528.347165283481, 6538.051982462516, 6601.108850305195, 6642.634655093968, 5839.737211825478, 6818.761343352959, 6839.965800170968, 6841.486888483384, 6850.109485766819, 5096.745162741848, 6344.0, 6344.714487848058, 6360.459360954278, 6379.649119298437, 6669.966650166718, 6754.560953538103, 6836.042953986446, 6907.930920690829, 6954.547954382949, 5516.586256030814, 6532.412082912466, 6568.244651865001, 6605.141331626858, 6615.37153970372, 6723.798286051427, 6805.693743781541, 6808.2510923798345, 6809.5233333666165, 6831.658417079127, 5954.404559544011, 6114.144019837227, 6425.967870160619, 6474.902876456857, 6607.141071660653, 6685.331466853276, 6708.83227919306, 6714.731410743613, 6725.092112564741, 6745.123133992648, 5360.303160589098, 5395.217693434509, 5400.21696853954, 5412.729363531807, 5441.075017247077, 4749.762511874393, 4773.37946067009, 4797.232442809134, 4806.158922188673, 4821.252705830602, 6289.7484100635465, 6314.210723659543, 6314.336994615591, 6316.557840951089, 6322.146510221153, 6736.427403670645, 6783.253842077367, 6801.353871382211, 6822.0, 6828.351306625863, 6157.168782214344, 6292.905606415908, 6336.20797400325, 6339.2709838368255, 6365.2679941806355, 5811.27359080115, 5910.940890591125, 5970.910436343458, 5980.491658209101, 5992.0712289595185, 6866.038754574363, 6892.551984121016, 6938.7571435001555, 6950.131233595801, 6951.6871740770775, 6707.027481015323, 6760.256371799, 6760.3239676032, 6787.117674702257, 6790.32096790317, 6657.567258128206, 6740.427063699641, 6874.931250687429, 6961.547499412522, 6999.020137180901, 6071.210742603434, 6604.207495100619, 6627.403533681992, 6633.402993730588, 6639.402453779183, 6081.6620343524355, 6738.831529211809, 6799.0, 6804.4556435484055, 6823.771721090251, 6146.293176284785, 6585.80242592723, 6592.571482853599, 6596.472282217315, 6609.537332386687, 6363.172787537591, 6408.359164083555, 6467.902981455188, 6485.805425837232, 6512.185976752906, 5371.785128594813, 6114.692773002257, 6358.618482891041, 6413.326600706859, 6453.612783233021, 4832.589229915497, 6487.253965793994, 6503.869922601427, 6555.737770489127, 6574.145361103026, 6204.906926396198, 6428.292887782247, 6431.0, 6438.742450301936, 6439.8068057958335], "value": 6080.200778267561, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.6143229075572, 709.2092317066471, 827.6523859978809, 1223.528941357577, 1291.2639795316672, 38210.85367438978, 38598.4210236846, 38614.8415547534, 38706.41940370897, 38783.61216387839, 39326.80336598313, 39352.62265820697, 39652.0, 39656.405153922715, 39677.60322396775, 7497.837835135562, 7706.921031055656, 8085.3531717462465, 8274.2966847818, 8279.793005174859, 7612.961935190344, 8455.661773529051, 8492.872606910902, 8514.191151840596, 8517.318614510825, 8425.578721063954, 8450.450720703135, 8523.786905327386, 8530.104339044405, 8533.871991920127, 8490.745277641681, 8493.405461617689, 8494.532800695959, 8497.980242370955, 8524.190201930807, 8211.425200235986, 8227.83544329116, 8270.0, 8285.0, 8286.337093032544, 36717.63282367169, 36769.816150919345, 36783.0, 36816.0, 36848.44727329092, 3593.82030898454, 3602.9099272518133, 3631.400818864884, 3638.909027274313, 3640.2355505343908, 30294.0, 30416.391672166654, 30543.93096241643, 30642.846785765927, 30654.0, 3077.984265192481, 3109.580206672093, 3110.8133511989354, 3117.127204382754, 3123.593932788723, 249577.0, 252275.0, 279049.60475197696, 307133.46433267917, 307633.0, 47469.05061898812, 47740.283895741595, 47814.0, 47815.521844781804, 47916.5208347919, 49374.025038998756, 49471.50528494741, 49523.50476495189, 49642.0, 49758.0, 14116.0, 14198.219097949712, 14276.500322488662, 14295.57113286603, 14297.85702142986, 11447.484863181176, 11535.826962595567, 11551.595694150832, 11556.711082223012, 11572.322013308023, 10742.78514429707, 13475.326233688276, 13495.122817017058, 13524.256165910969, 13576.796348054788, 6045.788397406158, 6075.635461872302, 6106.328303886569, 6115.1744514490865, 6119.837230926199, 1011.2365164300905, 1021.8416145497459, 1028.6039874648166, 1059.6450189186555, 1061.6125114333367, 19000.0, 22249.0, 22722.659160112613, 24075.036998519863, 24171.15400960993, 1114.7714718482853, 1124.8087825069758, 1130.8925652063042, 1164.173436859822, 1168.199783148546, 11986.981106606036, 12222.20555663879, 12260.509579616893, 12281.263124212575, 12328.137030407965, 306903.0, 312583.0, 312737.0, 313947.86052139185, 329993.0, 55343.16985245224, 55721.0, 55963.720181398836, 55994.880102398536, 56103.71948140233, 1463.7365274250737, 1503.1131632337192, 1504.2178067404889, 1506.495324066428, 1516.4161797707961, 27573.724262757114, 29426.0, 30225.546616801206, 30358.848205759714, 30536.541951870295, 37628.435573465875, 48750.0, 49777.751111244215, 49853.0, 50051.499485004686, 242326.0, 295761.0, 297135.0, 297168.0, 297633.0, 29811.0, 30288.848555757962, 33315.167120822174, 35432.82283588566, 35828.82085589555, 34817.12957176091, 41536.37695434631, 42041.369379459946, 43564.0, 43898.0, 29449.558256626595, 30115.54826677558, 30257.848710756305, 30333.544996824625, 30717.078487645405, 27056.594151088142, 28927.276818078873, 29587.704122958494, 30298.091057268317, 30654.693453065185, 31554.05574303212, 33834.66165338315, 36174.4573831398, 36357.546368045005, 36522.452163218106, 28702.0, 29923.55114673325, 30063.0, 30663.40048992669, 30778.0, 21768.45578860541, 23791.167309144097, 23907.402314942265, 24369.51260974735, 24379.805817476514], "value": 17555.51564612044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.0839466134464, 437.8489421149703, 440.22300639371497, 455.8450126956834, 458.8738097023318, 17049.488515344543, 19544.804551954476, 21796.564068718657, 22038.0, 22040.33878983631, 21843.235486757967, 21972.670409943865, 22062.0, 22229.2219772308, 22242.555148897012, 5978.9701051494685, 6132.325444201134, 6247.406496382859, 6255.342334281419, 6257.593256438339, 5979.431953964388, 5980.6411615303, 6099.298580663236, 6135.693215339237, 6148.262208534959, 5909.881802363951, 6078.848028799293, 6114.571979961404, 6114.602550834182, 6133.785317513888, 5957.672328021957, 6044.395560443986, 6087.54343424245, 6109.4501494865235, 6125.5712100153005, 5380.973095134499, 5384.111621582434, 5415.972920135414, 5442.374126975369, 5454.836354909359, 26993.0, 27694.584581231094, 27804.58293125605, 28048.0, 28144.577831332546, 2144.9034793434175, 2201.0315461197024, 2208.723909511311, 2219.5671843990426, 2221.48905751678, 15490.845091549052, 16781.496555103367, 17623.383181588582, 18086.457406277703, 18466.63066738671, 2123.7132987046707, 2147.3987283560627, 2171.0338899189865, 2173.532690471556, 2175.2060497918305, 160823.0, 161038.0, 162965.0, 164002.0, 164600.3539964597, 11606.88393116058, 11636.010939070104, 12550.372481376076, 12707.428165732565, 12879.09846310768, 16456.0, 16511.33954641825, 16551.337946482246, 16580.336786528645, 16599.253033613517, 8590.785230369165, 8885.333599979898, 8924.152205540464, 8932.285417166611, 9000.774980625536, 8304.667813287522, 8396.412251142483, 8407.369447291478, 8567.914320856711, 8581.35639827003, 6658.43403310724, 6716.597004179765, 7161.9641901790155, 7984.520928744294, 8074.717384891501, 1826.844718198968, 2137.5831712815852, 2161.6973623692697, 2162.7945345192184, 2329.2895666821782, 971.50453268832, 986.1420564109271, 986.6694657289745, 994.3039872089566, 1024.288119756762, 21060.473488162614, 21136.577268454854, 21277.89361053216, 21285.0, 21290.467738306357, 871.930245580352, 989.9901000989898, 999.3054826895238, 1000.9399436033862, 1030.0523518363125, 8595.312375009984, 9518.524073796421, 10536.789264214673, 10885.782284354269, 11012.39431831241, 454252.0, 521944.17083743773, 527775.0, 538164.0, 540164.0, 122748.0, 129829.0, 134091.32954335166, 136450.0, 138511.0, 1195.7668254690252, 1221.395409272416, 1247.444887025265, 1248.2635245205372, 1248.2759999200623, 21908.0, 23023.84880755922, 23127.537449251256, 23308.184213553122, 23431.41421464409, 45297.54702452933, 53011.20483192678, 56329.59176020468, 56896.29311120673, 57074.714626426605, 76976.61511692406, 78154.0, 78219.04452388508, 78634.6068269655, 79138.6043069781, 8887.244584210155, 8891.466512009041, 8915.331350148635, 9152.450852948577, 9195.0, 14362.353694083582, 14580.781288280896, 14697.70604587881, 14786.408543658563, 14873.107613543221, 10770.892291077302, 10949.288296260718, 11005.504752286006, 11751.412429378668, 12100.636980890587, 10187.847182292124, 11681.824772628588, 11683.299002059903, 11802.704932376759, 12055.397230138633, 11991.280523168638, 12118.878811212127, 12213.206141601124, 12219.87780122223, 12410.0, 7677.309042186007, 9096.363254572116, 9099.54502274897, 9154.489509231169, 9250.629974800933, 11895.286282822712, 11941.940290298493, 12115.57595484189, 12278.693032673897, 12287.815682764587], "value": 10458.390891774885, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.4577908609756, 1435.0456946130823, 1440.5030264558727, 1442.0986883198002, 1455.4178328668531, 11262.267952583077, 11435.771284574303, 11939.16425850192, 11947.163698541095, 12092.758144837098, 11627.0, 11740.94129529351, 11873.584424545143, 11934.104942129332, 11941.58204462844, 2555.8466492010493, 2565.320190149612, 2587.676540432446, 2592.7925765938776, 2599.5052844614424, 2526.1411120219122, 2553.3871870750977, 2583.2895953612815, 2585.121058839994, 2586.6378706981036, 2510.3598582361474, 2525.722170561246, 2556.6804149481313, 2560.6159076138633, 2561.0140096063055, 2507.448361360507, 2529.6584961030303, 2558.1941688368292, 2565.28172111809, 2569.486102779452, 2368.14746691191, 2525.623535173335, 2548.210054882992, 2559.897604095834, 2562.461883004571, 9691.418514889128, 11465.713357166054, 11585.18903676743, 11607.187496875222, 11640.126990475663, 6525.673716314165, 6680.098186744776, 6746.662666866686, 6748.527603067787, 6757.256701762803, 3944.2703099926607, 4274.487061552602, 4313.137372525507, 4342.630876375511, 4350.912981740379, 1220.9084318676046, 1235.962921112359, 1258.1004581724019, 1264.374134803269, 1272.872712728729, 14118.647033824134, 15302.617434564114, 16025.91987040069, 16134.515964521082, 16248.75626865598, 5384.811531596375, 5426.294581704353, 5530.391656917735, 5669.603127781016, 5686.289213848269, 11602.825957610643, 11739.0, 11766.647000589996, 11769.52921883115, 11780.646580602595, 6416.0, 6435.871282574322, 6457.3865482779065, 6461.515386346043, 6480.092787009822, 5993.550483713739, 6084.5436592255755, 6170.352113028157, 6186.350433204539, 6203.4416902479, 3743.2513497300647, 3762.435634654769, 4215.6205941464805, 4373.650107991353, 4426.22541055314, 1289.9097063205486, 1294.779887419141, 1298.740251949595, 1305.4647594486214, 1309.9934500327438, 698.4168219536743, 781.9335356494648, 789.740364119229, 806.2783808491367, 811.8822770698334, 6103.908441373383, 6179.474744646755, 6181.907271390933, 6238.968805155945, 6279.843003924939, 517.0615333170284, 582.9387914269025, 584.7193347193371, 612.1460562515266, 618.0667192539208, 3103.6275646922513, 3575.6781889630056, 4167.895802604899, 4303.332983387518, 4566.0411313623845, 14653.853461465462, 14711.852881471263, 14785.556433307018, 14891.0, 14903.105813651217, 14296.356663950148, 14503.347349369093, 14747.926260368844, 14915.925420372829, 14951.177685227416, 8341.582920853933, 8567.700130495403, 8583.356248281403, 8596.828063438696, 8597.441166324168, 6856.24581296067, 6960.164780226202, 7003.54476958996, 7035.788926332219, 7072.469564782559, 11646.825297620711, 12860.807087893501, 13241.0, 13373.933130334286, 13410.12834165776, 13361.398737056661, 13549.0, 13605.183688978694, 13687.657808554866, 13759.79360309576, 8159.26566609008, 8178.304844088319, 8236.752897413087, 8257.174282571696, 8257.669693212445, 10534.52594633228, 10770.946145269538, 10772.192085593768, 10779.568817247537, 10793.892061079603, 8920.018797932353, 8996.640134394813, 9040.457572545669, 9159.542022898695, 9294.349395542247, 8784.0, 8844.911550884666, 8875.467471951704, 8951.791508146316, 9018.413803102778, 8158.1026087129185, 8203.917960820316, 8228.177182281725, 8323.750287491384, 8329.0, 5117.667351622133, 5395.0, 5986.610870293417, 6075.969620151871, 6373.0, 6116.0, 6183.81448556544, 6206.844828879314, 6227.719752611053, 6285.340039295808], "value": 5267.419062016318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.8246138316, 384.1452767592108, 396.3539430727913, 398.1499498570551, 405.5174342532386, 6276.466500347465, 6287.559870809046, 6309.085182648513, 6334.746610135595, 6335.904961425582, 4454.26504626737, 6102.474381404649, 6300.338464461234, 6301.873962520747, 6303.527235457347, 3972.2651309507687, 4397.802098905557, 4454.933176002362, 4569.6115830154395, 4578.587927086562, 3840.3087444260022, 4497.730136191839, 4585.151746926829, 4606.286025666027, 4607.193741095311, 4537.773111344436, 4552.567506086916, 4560.794764235618, 4561.6806823522365, 4590.1278757036225, 3709.1654377765058, 4082.8775136745944, 4111.506619205715, 4546.158960592301, 4574.153781550407, 3226.677332266778, 3695.371786796224, 3881.4177873318945, 3894.2990261752875, 3916.5104361954754, 6115.021596544576, 6181.0, 6194.783182588588, 6212.9068063979075, 6227.501799856001, 4483.887902802423, 4818.397700287464, 5203.881165549425, 5248.396434410053, 5481.616286859921, 4910.607151427913, 6747.93252067478, 6749.493787965873, 6764.558985116382, 6767.154105736783, 4294.26997410441, 4420.31485119807, 4420.541221396931, 4428.424304840382, 4431.8448854289945, 6114.654775949284, 6955.339242771928, 7136.964315178391, 7163.7492687755675, 7253.419726421874, 4433.0, 4465.151621191963, 4469.374287599739, 4469.396631454777, 4473.843415480443, 3608.8376023079027, 4143.948745371001, 4414.448193975753, 4548.545145485491, 4561.270196768567, 5319.286904298652, 5390.51485366319, 5424.159255315432, 5428.2671839301975, 5472.370677372073, 5347.652402593897, 6200.627962322275, 6752.189737231465, 6762.0, 6767.898481522781, 1529.0, 1542.5141080559588, 1551.8215405228543, 1556.2218890554723, 1556.486359501362, 821.6483884010769, 978.9755256118511, 1004.4180415845074, 1012.3520946594185, 1021.2391768132788, 5566.387697353288, 6327.3988971047675, 6343.492520598434, 6352.936470635327, 6371.33101024395, 5818.301803783573, 6103.42017508336, 6436.259830119596, 6772.462450158406, 7005.369516743415, 5051.82318618854, 5357.785688572491, 6243.157173781572, 6285.025820997753, 6355.0785136155, 2921.985390073036, 2940.7941444099138, 3516.9472457913153, 3566.054995426214, 3631.1829838286176, 5725.4274572543245, 7045.295470452914, 7122.643867806589, 7159.821004474826, 7163.247858974837, 6007.909881351783, 6616.86766264672, 7085.397741191953, 7196.784096477009, 7205.351518363373, 6216.906746398718, 6538.1827271591055, 6907.792766217021, 7081.575105493687, 7205.747798827121, 6958.79123626292, 6960.79117626472, 6962.512624116466, 6970.79087627372, 7002.404795592432, 6125.387461253839, 6487.448566871869, 6781.321867813179, 6850.38346548813, 6871.9656401717675, 6918.0, 6980.568983358297, 6996.335348141918, 7022.683979220845, 7171.49799514029, 3728.8694895678523, 3954.4859168307935, 4199.661269290982, 4292.8838501989085, 4457.643388528974, 5579.442055794388, 6097.0, 6796.558223715443, 7021.1574611047, 7031.296870312928, 5940.405959404198, 6712.228093769279, 6752.919532874716, 6768.932310677027, 6773.830654233487, 6010.3088144863905, 6134.8159555213415, 6683.9331606685255, 6748.493862960199, 6754.223264324469, 5421.484958928895, 6115.510759139169, 6705.262421133769, 6709.4632429404555, 6733.494987876027, 5983.0, 6367.840803979938, 6569.540132190701, 6799.490038247052, 6877.0, 5393.411162641875, 5946.0, 6460.353964603502, 6645.867082658417, 6669.6331701754925], "value": 4773.834330434109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [809.7125520440239, 820.8809722590229, 827.1356432528007, 846.7764080903097, 849.5115308697499, 15762.133082680448, 16143.273552690127, 16171.353145874165, 18932.432027039176, 19063.428097157073, 18005.21890403014, 18054.638907221848, 18086.638267234648, 18371.44885653429, 18682.906585467055, 976.0971101730906, 985.374287327545, 998.6454808542935, 1001.4091685905314, 1004.2618675273668, 988.5057471264367, 1003.4781913405025, 1003.8996100389976, 1005.8088963096989, 1007.385494848142, 1058.6083149234794, 1058.9629362972298, 1060.3796778884323, 1062.0812996757827, 1064.4464878263298, 865.3942240431692, 1052.63157894737, 1054.5939813171947, 1055.6094245129314, 1055.8205105132147, 741.9814504637374, 749.6963729689485, 754.8188434775669, 763.9197884222187, 772.7102336623766, 16450.012999220085, 16527.669446611122, 16650.250738716786, 16677.916610416993, 16686.58283542909, 16526.66946661072, 16576.502704918752, 16963.23665435046, 17083.658326833392, 17116.914415427967, 14015.948803839752, 14030.508932187426, 14215.223097112861, 14279.35742891562, 14298.428062877472, 3466.9133271668156, 3530.91172720684, 3535.939218234545, 3536.469529570559, 3541.5218945442552, 117452.41273793577, 133870.6612933868, 134373.3281333597, 134787.0, 136569.26861462815, 11841.28952262867, 13401.66495837612, 13409.530666426628, 13412.12821166621, 13562.796558051637, 25540.74459255421, 25554.48891022169, 25913.61129583058, 26032.73967260341, 26334.0, 7573.2805383488485, 7883.0, 7970.4819186752675, 8009.799755006172, 8035.196480351918, 3956.5449973252867, 4004.2592120457807, 4005.0588111793704, 4012.7191096622955, 4068.16602596467, 4295.935560966588, 4304.246756817544, 4324.0919406924895, 4380.737155770664, 4398.252297109499, 931.3806318797923, 949.9097585729482, 954.8090381923643, 954.9236061115095, 956.1442508954447, 647.9546431749732, 664.7540410048313, 669.6450881033061, 680.2143524229523, 688.4664385101486, 10482.37105773656, 10941.507632156572, 11927.582534611247, 11962.760744785055, 12075.879241207651, 630.3224034163293, 658.2232965101226, 666.7033170239293, 670.9597424154566, 676.5129107042922, 4664.370310008106, 5420.83737487876, 5541.667499950016, 5562.888742225214, 5735.541156707453, 103806.0, 107161.39257911137, 107168.0, 107441.46279268553, 108580.0, 63038.68480657567, 88266.0, 90660.73348166348, 91000.54499727458, 91279.5436022829, 56901.0, 74761.38335158244, 81075.18924810676, 82592.5018622866, 83560.58219708863, 10483.737906552396, 10638.042576168182, 11450.370229637118, 11457.083433325148, 11593.362365070003, 34902.60389584388, 39630.216640251834, 40879.59120408758, 41256.381154282106, 41364.586354137275, 53726.73136634448, 54181.0, 54661.72669136629, 55301.72349138228, 55612.05357812457, 14132.858671413154, 14163.929180354444, 14230.430782768573, 14417.279136043366, 14478.493252736103, 15155.393784248507, 15486.148261845256, 15538.61153471127, 15718.685626287639, 15726.449574264845, 14467.421303147756, 14566.781498277322, 14603.415863365772, 14826.851731482548, 14888.181150036851, 11802.291862488279, 13498.190108593515, 13903.374348154559, 14433.278336083364, 14435.639109022359, 11624.535018599161, 14072.366743496772, 14152.363143658355, 14153.929230353782, 14258.928705356822, 10552.683419497427, 10615.1507879371, 10920.563177472812, 10926.890731092906, 11226.045786107945, 11676.299422034705, 11799.115066370228, 11837.230580012272, 11866.169368144147, 12030.75938481243], "value": 8141.833395711294, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 116.0, 114.0, 113.0, 116.0, 115.0, 114.0, 112.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 132.0, 114.0, 155.0, 114.0, 115.0, 120.0, 117.0, 141.0, 112.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 194.0, 228.0, 228.0, 204.0, 214.0, 204.0, 196.0, 199.0, 223.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 63.0, 84.0, 67.0, 72.0, 51.0, 52.0, 76.0, 48.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 111.0, 104.0, 84.0, 69.0, 85.0, 74.0, 71.0, 91.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 90.0, 91.0, 97.0, 91.0, 90.0, 90.0, 88.0, 90.0, 89.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 97.0, 98.0, 97.0, 97.0, 98.0, 103.0, 100.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 140.0, 146.0, 91.0, 90.0, 92.0, 92.0, 110.0, 112.0, 89.0], "value": 106.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 51.0, 50.0, 51.0, 50.0, 52.0, 52.0, 52.0, 51.0, 52.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 62.0, 84.0, 95.0, 52.0, 59.0, 78.0, 85.0, 83.0, 86.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 83.0, 84.0, 84.0, 82.0, 93.0, 79.0, 110.0, 110.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 145.0, 137.0, 152.0, 147.0, 138.0, 140.0, 196.0, 138.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 191.0, 187.0, 186.0, 183.0, 188.0, 186.0, 186.0, 188.0, 191.0], "value": 187.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 67.0, 70.0, 70.0, 69.0, 68.0, 69.0, 70.0, 71.0], "value": 69.5, "unit": "score"}]}]} 08:40:57 INFO - Return code: 0 08:40:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:40:57 INFO - # TBPL SUCCESS # 08:40:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:40:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:40:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:40:57 INFO - 2017-05-22 08:40:57.085 screenresolution[1661:10664] starting screenresolution argv=screenresolution get 08:40:57 INFO - 2017-05-22 08:40:57.087 screenresolution[1661:10664] Display 0: 1600x1200x32@60 08:40:57 INFO - 2017-05-22 08:40:57.094 screenresolution[1662:10667] starting screenresolution argv=screenresolution list 08:40:57 INFO - Available Modes on Display 0 08:40:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:40:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:40:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:40:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:40:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:40:57 INFO - Intel Iris: 08:40:57 INFO - Chipset Model: Intel Iris 08:40:57 INFO - Type: GPU 08:40:57 INFO - Bus: Built-In 08:40:57 INFO - VRAM (Dynamic, Max): 1536 MB 08:40:57 INFO - Vendor: Intel (0x8086) 08:40:57 INFO - Device ID: 0x0a2e 08:40:57 INFO - Revision ID: 0x0009 08:40:57 INFO - Displays: 08:40:57 INFO - Display: 08:40:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:40:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:40:57 INFO - Display Serial Number: 1600x1200 60 08:40:57 INFO - Main Display: Yes 08:40:57 INFO - Mirror: Off 08:40:57 INFO - Online: Yes 08:40:57 INFO - Rotation: Supported 08:40:57 INFO - Return code: 0 08:40:57 INFO - Running post-action listener: _resource_record_post_action 08:40:57 INFO - [mozharness: 2017-05-22 15:40:57.331316Z] Finished run-tests step (success) 08:40:57 INFO - Running post-run listener: _resource_record_post_run 08:40:57 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39320576; Write bytes: 566508032; Read time: 252; Write time: 1714 08:40:57 INFO - TinderboxPrint: CPU usage
54.2% 08:40:57 INFO - TinderboxPrint: I/O read bytes / time
39,320,576 / 252 08:40:57 INFO - TinderboxPrint: I/O write bytes / time
566,508,032 / 1,714 08:40:57 INFO - TinderboxPrint: CPU idle
2,193.1 (45.8%) 08:40:57 INFO - TinderboxPrint: CPU system
963.7 (20.1%) 08:40:57 INFO - TinderboxPrint: CPU user
1,628.8 (34.0%) 08:40:57 INFO - TinderboxPrint: Swap in / out
284,581,888 / 0 08:40:57 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 229935616; Write bytes: 230262784; Read time: 16875; Write time: 498 08:40:57 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 38358016; Write bytes: 317809152; Read time: 239; Write time: 1166 08:40:57 INFO - Running post-run listener: _upload_blobber_files 08:40:57 INFO - Blob upload gear active. 08:40:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:40:57 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 08:40:57 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'] 08:40:57 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 08:40:58 INFO - (blobuploader) - INFO - Open directory for files ... 08:40:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:40:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:40:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:59 INFO - (blobuploader) - INFO - Done attempting. 08:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:41:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:00 INFO - (blobuploader) - INFO - Done attempting. 08:41:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:41:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:41:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:01 INFO - (blobuploader) - INFO - Done attempting. 08:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:03 INFO - (blobuploader) - INFO - Done attempting. 08:41:03 INFO - (blobuploader) - INFO - Iteration through files over. 08:41:03 INFO - Return code: 0 08:41:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:41:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:41:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc38719a52f8c092bd91f23ccb116a326ab6a0b4603b7130273778de49bdd3a0224fdc41b573305d65833094b91be25bd1c242b8040c606d6a284d334ff5d8bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/493ee96c7fd43d2cd066dd72073b15e08bb435e2e2b453901d56e7d9427f913272a6403167080acad5323fb39334c8f53c0a21785fa656def27d2f975e587af0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c23ad276f9a59aac480022b3f19a2c7074d30cce73bbecae5411b6bd1d8dd25fe3f23a97bfa7fb54f4e649f6c01b2a4afef19c6c446b7a57f0e99e0c107417fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62f269b3ae4565b633e0310a38b93129f2c346483503865b69773486df995f4c0db350db5586968e83b1f42faec148b63dc14df0fa33ab1d64b6a89d8a39525a"} 08:41:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:41:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:41:03 INFO - Contents: 08:41:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc38719a52f8c092bd91f23ccb116a326ab6a0b4603b7130273778de49bdd3a0224fdc41b573305d65833094b91be25bd1c242b8040c606d6a284d334ff5d8bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/493ee96c7fd43d2cd066dd72073b15e08bb435e2e2b453901d56e7d9427f913272a6403167080acad5323fb39334c8f53c0a21785fa656def27d2f975e587af0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c23ad276f9a59aac480022b3f19a2c7074d30cce73bbecae5411b6bd1d8dd25fe3f23a97bfa7fb54f4e649f6c01b2a4afef19c6c446b7a57f0e99e0c107417fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62f269b3ae4565b633e0310a38b93129f2c346483503865b69773486df995f4c0db350db5586968e83b1f42faec148b63dc14df0fa33ab1d64b6a89d8a39525a"} 08:41:03 INFO - Running post-run listener: copy_logs_to_upload_dir 08:41:03 INFO - Copying logs to upload dir... 08:41:03 INFO - mkdir: /builds/slave/test/build/upload/logs 08:41:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.788328 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-22 08:41:03.579796) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 08:41:03.583536) ========= 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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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/fc38719a52f8c092bd91f23ccb116a326ab6a0b4603b7130273778de49bdd3a0224fdc41b573305d65833094b91be25bd1c242b8040c606d6a284d334ff5d8bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/493ee96c7fd43d2cd066dd72073b15e08bb435e2e2b453901d56e7d9427f913272a6403167080acad5323fb39334c8f53c0a21785fa656def27d2f975e587af0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c23ad276f9a59aac480022b3f19a2c7074d30cce73bbecae5411b6bd1d8dd25fe3f23a97bfa7fb54f4e649f6c01b2a4afef19c6c446b7a57f0e99e0c107417fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62f269b3ae4565b633e0310a38b93129f2c346483503865b69773486df995f4c0db350db5586968e83b1f42faec148b63dc14df0fa33ab1d64b6a89d8a39525a"} build_url:https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010933 build_url: 'https://queue.taskcluster.net/v1/task/RUflUMD3SZSJyN5VXyPsxg/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/fc38719a52f8c092bd91f23ccb116a326ab6a0b4603b7130273778de49bdd3a0224fdc41b573305d65833094b91be25bd1c242b8040c606d6a284d334ff5d8bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/493ee96c7fd43d2cd066dd72073b15e08bb435e2e2b453901d56e7d9427f913272a6403167080acad5323fb39334c8f53c0a21785fa656def27d2f975e587af0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c23ad276f9a59aac480022b3f19a2c7074d30cce73bbecae5411b6bd1d8dd25fe3f23a97bfa7fb54f4e649f6c01b2a4afef19c6c446b7a57f0e99e0c107417fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62f269b3ae4565b633e0310a38b93129f2c346483503865b69773486df995f4c0db350db5586968e83b1f42faec148b63dc14df0fa33ab1d64b6a89d8a39525a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 08:41:03.616665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 08:41:03.617122) ========= 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.qrNO3jyNM3/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.q8xgBeY6PP/Listeners TMPDIR=/var/folders/s0/f6nsqk417rl96stczq35c_fw00000w/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.005268 ========= master_lag: 0.32 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 08:41:03.945655) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 08:41:03.946233) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 08:41:03.947316) ========= ========= Total master_lag: 1.74 =========