builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1494783886.44 results: success (0) revision: 626efff0df630961981fc6875571fe05725f3636 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:46.436417) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:46.436942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:46.437255) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:46.462358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:46.462629) ========= 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.rxynXPlolY/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.w5F87QGRsp/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-14 10:44:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.08M=0.001s 2017-05-14 10:44:47 (8.08 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.685198 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:47.170637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:47.170971) ========= 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.rxynXPlolY/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.w5F87QGRsp/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.020635 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:47.220169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-14 10:44:47.220509) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 626efff0df630961981fc6875571fe05725f3636 --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 626efff0df630961981fc6875571fe05725f3636 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rxynXPlolY/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.w5F87QGRsp/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-14 10:44:47,310 truncating revision to first 12 chars 2017-05-14 10:44:47,310 Setting DEBUG logging. 2017-05-14 10:44:47,310 attempt 1/10 2017-05-14 10:44:47,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/626efff0df63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-14 10:44:48,230 unpacking tar archive at: mozilla-central-626efff0df63/testing/mozharness/ program finished with exit code 0 elapsedTime=1.182658 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-14 10:44:48.426892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:48.427395) ========= script_repo_revision: 626efff0df630961981fc6875571fe05725f3636 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:48.427959) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:48.428394) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 10:44:48.448873) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-14 10:44:48.449426) ========= /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.rxynXPlolY/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.w5F87QGRsp/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:44:48 INFO - MultiFileLogger online at 20170514 10:44:48 in /builds/slave/test 10:44:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:44:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:44:48 INFO - {'append_to_log': False, 10:44:48 INFO - 'base_work_dir': '/builds/slave/test', 10:44:48 INFO - 'blob_upload_branch': 'Firefox', 10:44:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:44:48 INFO - 'branch': 'Firefox', 10:44:48 INFO - 'buildbot_json_path': 'buildprops.json', 10:44:48 INFO - 'config_files': ('talos/mac_config.py',), 10:44:48 INFO - 'default_actions': ('clobber', 10:44:48 INFO - 'read-buildbot-config', 10:44:48 INFO - 'download-and-extract', 10:44:48 INFO - 'populate-webroot', 10:44:48 INFO - 'create-virtualenv', 10:44:48 INFO - 'install', 10:44:48 INFO - 'run-tests'), 10:44:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:44:48 INFO - 'download_minidump_stackwalk': True, 10:44:48 INFO - 'download_symbols': 'ondemand', 10:44:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:44:48 INFO - 'tooltool.py': '/tools/tooltool.py', 10:44:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:44:48 INFO - '/tools/misc-python/virtualenv.py')}, 10:44:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:44:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:44:48 INFO - 'gecko_profile': False, 10:44:48 INFO - 'gecko_profile_interval': 0, 10:44:48 INFO - 'installer_path': 'installer.exe', 10:44:48 INFO - 'log_level': 'info', 10:44:48 INFO - 'log_name': 'talos', 10:44:48 INFO - 'log_to_console': True, 10:44:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:44:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:44:48 INFO - 'opt_config_files': (), 10:44:48 INFO - 'pip_index': False, 10:44:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:48 INFO - 'cmd': ('bash', 10:44:48 INFO - '-c', 10:44:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:48 INFO - 'enabled': True, 10:44:48 INFO - 'halt_on_failure': False, 10:44:48 INFO - 'name': 'check_screen_resolution'},), 10:44:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:44:48 INFO - 'cmd': ('bash', 10:44:48 INFO - '-c', 10:44:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:44:48 INFO - 'enabled': True, 10:44:48 INFO - 'halt_on_failure': False, 10:44:48 INFO - 'name': 'check_screen_resolution'},), 10:44:48 INFO - 'run_cmd_checks_enabled': True, 10:44:48 INFO - 'suite': 'dromaeojs-e10s', 10:44:48 INFO - 'system_bits': '32', 10:44:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:44:48 INFO - 'title': 't-yosemite-r7-0276', 10:44:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:44:48 INFO - 'use_talos_json': True, 10:44:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:44:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:44:48 INFO - 'work_dir': 'build'} 10:44:48 INFO - [mozharness: 2017-05-14 17:44:48.663353Z] Running clobber step. 10:44:48 INFO - Running pre-action listener: _resource_record_pre_action 10:44:48 INFO - Running main action method: clobber 10:44:48 INFO - rmtree: /builds/slave/test/build 10:44:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:44:50 INFO - Running post-action listener: _resource_record_post_action 10:44:50 INFO - [mozharness: 2017-05-14 17:44:50.413408Z] Finished clobber step (success) 10:44:50 INFO - [mozharness: 2017-05-14 17:44:50.413566Z] Running read-buildbot-config step. 10:44:50 INFO - Running pre-action listener: _resource_record_pre_action 10:44:50 INFO - Running main action method: read_buildbot_config 10:44:50 INFO - Using buildbot properties: 10:44:50 INFO - { 10:44:50 INFO - "project": "", 10:44:50 INFO - "product": "firefox", 10:44:50 INFO - "who": "archaeopteryx@coole-files.de", 10:44:50 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:44:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:44:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:44:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:44:50 INFO - "stage_platform": "macosx64", 10:44:50 INFO - "basedir": "/builds/slave/test", 10:44:50 INFO - "buildnumber": 62, 10:44:50 INFO - "platform": "yosemite_r7", 10:44:50 INFO - "parent_task_id": "bX8vx-rlTe266dnO5Ihn0g", 10:44:50 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:44:50 INFO - "slavebuilddir": "test", 10:44:50 INFO - "taskId": "BBvOCMDeQu-8W1taL4358w", 10:44:50 INFO - "branch": "mozilla-central", 10:44:50 INFO - "script_repo_revision": "production", 10:44:50 INFO - "revision": "626efff0df630961981fc6875571fe05725f3636", 10:44:50 INFO - "slavename": "t-yosemite-r7-0276", 10:44:50 INFO - "repo_path": "mozilla-central" 10:44:50 INFO - } 10:44:50 INFO - Finding installer, test and symbols from parent task. 10:44:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BBvOCMDeQu-8W1taL4358w'}, attempt #1 10:44:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BBvOCMDeQu-8W1taL4358w'}, attempt #1 10:44:51 INFO - Task dependencies: Fhjhjc1kSbCrqu8sp3jLnQ 10:44:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g'}, attempt #1 10:44:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:44:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/test_packages.json 10:44:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/target.crashreporter-symbols.zip 10:44:52 INFO - Running post-action listener: _resource_record_post_action 10:44:52 INFO - [mozharness: 2017-05-14 17:44:52.230532Z] Finished read-buildbot-config step (success) 10:44:52 INFO - [mozharness: 2017-05-14 17:44:52.230676Z] Running download-and-extract step. 10:44:52 INFO - Running pre-action listener: _resource_record_pre_action 10:44:52 INFO - Running main action method: download_and_extract 10:44:52 INFO - mkdir: /builds/slave/test/build/tests 10:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:44:52 INFO - trying https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:44:52 INFO - Downloading https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:44:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:44:53 INFO - Downloaded 1325 bytes. 10:44:53 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:44:53 INFO - Using the following test package requirements: 10:44:53 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:44:53 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:44:53 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:44:53 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:44:53 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'jsshell-mac.zip'], 10:44:53 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:44:53 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:44:53 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:44:53 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:44:53 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:44:53 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:44:53 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:44:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:44:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:44:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:44:53 INFO - Fetch https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:44:54 INFO - Content-Length response header: 56564799 10:44:54 INFO - Bytes received: 56564799 10:45:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:45:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:45:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:45:12 INFO - Fetch https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:45:13 INFO - Content-Length response header: 56564799 10:45:13 INFO - Bytes received: 56564799 10:45:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:45:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:45:30 INFO - Fetch https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:45:32 INFO - Content-Length response header: 14410013 10:45:32 INFO - Bytes received: 14410013 10:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:32 INFO - trying https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:45:32 INFO - Downloading https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:45:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:45:34 INFO - Downloaded 75961987 bytes. 10:45:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:45:34 INFO - mkdir: /builds/slave/test/properties 10:45:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:45:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:45:34 INFO - Contents: 10:45:34 INFO - build_url:https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:45:34 INFO - Running post-action listener: _resource_record_post_action 10:45:34 INFO - Running post-action listener: set_extra_try_arguments 10:45:34 INFO - [mozharness: 2017-05-14 17:45:34.465830Z] Finished download-and-extract step (success) 10:45:34 INFO - [mozharness: 2017-05-14 17:45:34.465972Z] Running populate-webroot step. 10:45:34 INFO - Running pre-action listener: _resource_record_pre_action 10:45:34 INFO - Running main action method: populate_webroot 10:45:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:45:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:45:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:45:34 INFO - '--tppagecycles', 10:45:34 INFO - '7'], 10:45:34 INFO - 'tests': ['tsvgm']}}, 10:45:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:45:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:45:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:45:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:45:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:45:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:45:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['damp', 'tps']}, 10:45:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'tests': ['damp', 'tps']}, 10:45:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['dromaeo_dom']}, 10:45:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:45:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:45:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:45:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['a11yr', 10:45:34 INFO - 'ts_paint', 10:45:34 INFO - 'tpaint', 10:45:34 INFO - 'sessionrestore', 10:45:34 INFO - 'sessionrestore_no_auto_restore', 10:45:34 INFO - 'tabpaint']}, 10:45:34 INFO - 'other-e10s': {'tests': ['a11yr', 10:45:34 INFO - 'ts_paint', 10:45:34 INFO - 'tpaint', 10:45:34 INFO - 'sessionrestore', 10:45:34 INFO - 'sessionrestore_no_auto_restore', 10:45:34 INFO - 'tabpaint']}, 10:45:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:45:34 INFO - 'ts_paint', 10:45:34 INFO - 'tpaint', 10:45:34 INFO - 'sessionrestore', 10:45:34 INFO - 'sessionrestore_no_auto_restore', 10:45:34 INFO - 'tabpaint']}, 10:45:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:45:34 INFO - 'ts_paint', 10:45:34 INFO - 'tpaint', 10:45:34 INFO - 'sessionrestore', 10:45:34 INFO - 'sessionrestore_no_auto_restore', 10:45:34 INFO - 'tabpaint']}, 10:45:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['a11yr', 10:45:34 INFO - 'ts_paint', 10:45:34 INFO - 'tpaint', 10:45:34 INFO - 'sessionrestore', 10:45:34 INFO - 'sessionrestore_no_auto_restore', 10:45:34 INFO - 'tabpaint']}, 10:45:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['a11yr', 10:45:34 INFO - 'ts_paint', 10:45:34 INFO - 'tpaint', 10:45:34 INFO - 'sessionrestore', 10:45:34 INFO - 'sessionrestore_no_auto_restore', 10:45:34 INFO - 'tabpaint']}, 10:45:34 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:45:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:45:34 INFO - 'bloom_basic_ref']}, 10:45:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['tsvgx', 10:45:34 INFO - 'tsvgr_opacity', 10:45:34 INFO - 'tart', 10:45:34 INFO - 'tscrollx', 10:45:34 INFO - 'cart', 10:45:34 INFO - 'tsvg_static']}, 10:45:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:45:34 INFO - 'tsvgr_opacity', 10:45:34 INFO - 'tart', 10:45:34 INFO - 'tscrollx', 10:45:34 INFO - 'cart', 10:45:34 INFO - 'tsvg_static']}, 10:45:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'talos_options': ['--disable-e10s'], 10:45:34 INFO - 'tests': ['tp5o']}, 10:45:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:45:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'talos_options': ['--disable-e10s', 10:45:34 INFO - '--xperf_path', 10:45:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:45:34 INFO - 'tests': ['tp5n']}, 10:45:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:45:34 INFO - 'talos_options': ['--xperf_path', 10:45:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:45:34 INFO - 'tests': ['tp5n']}}, 10:45:34 INFO - 'talos.zip': {'path': '', 10:45:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:45:34 INFO - Running post-action listener: _resource_record_post_action 10:45:34 INFO - [mozharness: 2017-05-14 17:45:34.486624Z] Finished populate-webroot step (success) 10:45:34 INFO - [mozharness: 2017-05-14 17:45:34.486730Z] Running create-virtualenv step. 10:45:34 INFO - Running pre-action listener: _resource_record_pre_action 10:45:34 INFO - Running main action method: create_virtualenv 10:45:34 INFO - Creating virtualenv /builds/slave/test/build/venv 10:45:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:45:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:45:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:45:34 INFO - Using real prefix '/tools/python27' 10:45:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:45:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:45:38 INFO - Installing pip.................done. 10:45:38 INFO - Return code: 0 10:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:45:38 INFO - Reading from file tmpfile_stdout 10:45:38 INFO - Output received: 10:45:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:45:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45: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')]} 10:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45: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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45: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 10:45: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 10:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:38 INFO - 'HOME': '/Users/cltbld', 10:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:38 INFO - 'LOGNAME': 'cltbld', 10:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:38 INFO - 'MOZ_NO_REMOTE': '1', 10:45:38 INFO - 'NO_EM_RESTART': '1', 10:45:38 INFO - 'PAGER': '/bin/cat', 10:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:38 INFO - 'PWD': '/builds/slave/test', 10:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:38 INFO - 'SHELL': '/bin/bash', 10:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:38 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:38 INFO - 'USER': 'cltbld', 10:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:38 INFO - 'XPC_FLAGS': '0x0', 10:45:38 INFO - 'XPC_SERVICE_NAME': '0', 10:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45: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 10:45:38 INFO - Cleaning up... 10:45:38 INFO - Return code: 0 10:45:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45: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')]} 10:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45: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', '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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:45: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 psutil>=3.1.1 10:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:38 INFO - 'HOME': '/Users/cltbld', 10:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:38 INFO - 'LOGNAME': 'cltbld', 10:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:38 INFO - 'MOZ_NO_REMOTE': '1', 10:45:38 INFO - 'NO_EM_RESTART': '1', 10:45:38 INFO - 'PAGER': '/bin/cat', 10:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:38 INFO - 'PWD': '/builds/slave/test', 10:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:38 INFO - 'SHELL': '/bin/bash', 10:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:38 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:38 INFO - 'USER': 'cltbld', 10:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:38 INFO - 'XPC_FLAGS': '0x0', 10:45:38 INFO - 'XPC_SERVICE_NAME': '0', 10:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:38 INFO - Downloading/unpacking psutil>=3.1.1 10:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:45:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:45:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:45:41 INFO - Installing collected packages: psutil 10:45:41 INFO - Running setup.py install for psutil 10:45:41 INFO - building 'psutil._psutil_osx' extension 10:45: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 10:45: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:45: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:45:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:45:41 INFO - building 'psutil._psutil_posix' extension 10:45:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:45:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:45:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:45:41 INFO - ^ 10:45:41 INFO - 1 warning generated. 10:45:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:45:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:45:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:45:42 INFO - Successfully installed psutil 10:45:42 INFO - Cleaning up... 10:45:42 INFO - Return code: 0 10:45:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45: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')]} 10:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45: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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45: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 10:45: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 10:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:42 INFO - 'HOME': '/Users/cltbld', 10:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:42 INFO - 'LOGNAME': 'cltbld', 10:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:42 INFO - 'MOZ_NO_REMOTE': '1', 10:45:42 INFO - 'NO_EM_RESTART': '1', 10:45:42 INFO - 'PAGER': '/bin/cat', 10:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:42 INFO - 'PWD': '/builds/slave/test', 10:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:42 INFO - 'SHELL': '/bin/bash', 10:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:42 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:42 INFO - 'USER': 'cltbld', 10:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:42 INFO - 'XPC_FLAGS': '0x0', 10:45:42 INFO - 'XPC_SERVICE_NAME': '0', 10:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:45:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:45:43 INFO - Installing collected packages: mozsystemmonitor 10:45:43 INFO - Running setup.py install for mozsystemmonitor 10:45:44 INFO - Successfully installed mozsystemmonitor 10:45:44 INFO - Cleaning up... 10:45:44 INFO - Return code: 0 10:45:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45: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')]} 10:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45: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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45: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 10:45: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 10:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:44 INFO - 'HOME': '/Users/cltbld', 10:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:44 INFO - 'LOGNAME': 'cltbld', 10:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:44 INFO - 'MOZ_NO_REMOTE': '1', 10:45:44 INFO - 'NO_EM_RESTART': '1', 10:45:44 INFO - 'PAGER': '/bin/cat', 10:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:44 INFO - 'PWD': '/builds/slave/test', 10:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:44 INFO - 'SHELL': '/bin/bash', 10:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:44 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:44 INFO - 'USER': 'cltbld', 10:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:44 INFO - 'XPC_FLAGS': '0x0', 10:45:44 INFO - 'XPC_SERVICE_NAME': '0', 10:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:44 INFO - Downloading/unpacking jsonschema==2.5.1 10:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:45:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:45: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 10:45: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 10:45: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 10:45: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 10:45:46 INFO - Downloading functools32-3.2.3-2.tar.gz 10:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:45:46 INFO - warning: no files found matching '*.txt' 10:45:46 INFO - no previously-included directories found matching 'build' 10:45:46 INFO - no previously-included directories found matching 'dist' 10:45:46 INFO - no previously-included directories found matching '.git*' 10:45:46 INFO - Installing collected packages: jsonschema, functools32 10:45:46 INFO - Running setup.py install for functools32 10:45:46 INFO - warning: no files found matching '*.txt' 10:45:46 INFO - no previously-included directories found matching 'build' 10:45:46 INFO - no previously-included directories found matching 'dist' 10:45:46 INFO - no previously-included directories found matching '.git*' 10:45:46 INFO - Successfully installed jsonschema functools32 10:45:46 INFO - Cleaning up... 10:45:46 INFO - Return code: 0 10:45:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:46 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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:46 INFO - 'HOME': '/Users/cltbld', 10:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:46 INFO - 'LOGNAME': 'cltbld', 10:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:46 INFO - 'MOZ_NO_REMOTE': '1', 10:45:46 INFO - 'NO_EM_RESTART': '1', 10:45:46 INFO - 'PAGER': '/bin/cat', 10:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:46 INFO - 'PWD': '/builds/slave/test', 10:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:46 INFO - 'SHELL': '/bin/bash', 10:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:46 INFO - 'USER': 'cltbld', 10:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:46 INFO - 'XPC_FLAGS': '0x0', 10:45:46 INFO - 'XPC_SERVICE_NAME': '0', 10:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:45:46 INFO - Cleaning up... 10:45:46 INFO - Return code: 0 10:45:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:46 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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:45:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:46 INFO - 'HOME': '/Users/cltbld', 10:45:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:46 INFO - 'LOGNAME': 'cltbld', 10:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:46 INFO - 'MOZ_NO_REMOTE': '1', 10:45:46 INFO - 'NO_EM_RESTART': '1', 10:45:46 INFO - 'PAGER': '/bin/cat', 10:45:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:46 INFO - 'PWD': '/builds/slave/test', 10:45:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:46 INFO - 'SHELL': '/bin/bash', 10:45:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:46 INFO - 'USER': 'cltbld', 10:45:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:46 INFO - 'XPC_FLAGS': '0x0', 10:45:46 INFO - 'XPC_SERVICE_NAME': '0', 10:45:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:46 INFO - Downloading/unpacking blobuploader==1.2.4 10:45: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 10:45: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 10:45: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 10:45: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 10:45:48 INFO - Downloading blobuploader-1.2.4.tar.gz 10:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:45:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:45:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:45: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 10:45: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 10:45: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 10:45: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 10:45:49 INFO - Downloading docopt-0.6.1.tar.gz 10:45:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:45:49 INFO - Installing collected packages: blobuploader, requests, docopt 10:45:49 INFO - Running setup.py install for blobuploader 10:45:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:45:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:45:49 INFO - Running setup.py install for requests 10:45:50 INFO - Running setup.py install for docopt 10:45:50 INFO - Successfully installed blobuploader requests docopt 10:45:50 INFO - Cleaning up... 10:45:50 INFO - Return code: 0 10:45:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45: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')]} 10:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45: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')]} 10:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:50 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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:50 INFO - 'HOME': '/Users/cltbld', 10:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:50 INFO - 'LOGNAME': 'cltbld', 10:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:50 INFO - 'MOZ_NO_REMOTE': '1', 10:45:50 INFO - 'NO_EM_RESTART': '1', 10:45:50 INFO - 'PAGER': '/bin/cat', 10:45:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:50 INFO - 'PWD': '/builds/slave/test', 10:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:50 INFO - 'SHELL': '/bin/bash', 10:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:50 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:50 INFO - 'USER': 'cltbld', 10:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:50 INFO - 'XPC_FLAGS': '0x0', 10:45:50 INFO - 'XPC_SERVICE_NAME': '0', 10:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-T5o8A3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-avQD4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nllfLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-PUI9eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YfmoMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:50 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-T7pq8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MfE14_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-1Rvo2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-SjTAee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UDoFYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-IIQWEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-k5l0kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_Tj7M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UDAzq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:51 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-jYta2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iM0wM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:52 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-zjm_6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:45:52 INFO - Running setup.py install for manifestparser 10:45:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:45:52 INFO - Running setup.py install for mozcrash 10:45:52 INFO - Running setup.py install for mozdebug 10:45:52 INFO - Running setup.py install for mozdevice 10:45:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:45:52 INFO - Running setup.py install for mozfile 10:45:52 INFO - Running setup.py install for mozhttpd 10:45:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Running setup.py install for mozinfo 10:45:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Running setup.py install for mozInstall 10:45:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Running setup.py install for mozleak 10:45:53 INFO - Running setup.py install for mozlog 10:45:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Running setup.py install for moznetwork 10:45:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:45:53 INFO - Running setup.py install for mozprocess 10:45:54 INFO - Running setup.py install for mozprofile 10:45:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:45:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:45:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:45:54 INFO - Running setup.py install for mozrunner 10:45:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:45:54 INFO - Running setup.py install for mozscreenshot 10:45:54 INFO - Running setup.py install for moztest 10:45:54 INFO - Running setup.py install for mozversion 10:45:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:45:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:45:54 INFO - Cleaning up... 10:45:54 INFO - Return code: 0 10:45:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:54 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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:54 INFO - 'HOME': '/Users/cltbld', 10:45:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:54 INFO - 'LOGNAME': 'cltbld', 10:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:54 INFO - 'MOZ_NO_REMOTE': '1', 10:45:54 INFO - 'NO_EM_RESTART': '1', 10:45:54 INFO - 'PAGER': '/bin/cat', 10:45:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:54 INFO - 'PWD': '/builds/slave/test', 10:45:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:54 INFO - 'SHELL': '/bin/bash', 10:45:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:54 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:54 INFO - 'USER': 'cltbld', 10:45:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:54 INFO - 'XPC_FLAGS': '0x0', 10:45:54 INFO - 'XPC_SERVICE_NAME': '0', 10:45:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-_bclRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:45: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)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-RacAFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tVPkcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iit2t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-yIXHzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-nt0nPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ghF_kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-LrVdUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:45:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:45:55 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-O1G31W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:45: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)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-dKaikP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:45: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)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-bTmIH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:45: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)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6LAqqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KfwRCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-DPAtl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-j8K80H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-IH9oES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:45:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:45:56 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-PhGth5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:45:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:45:58 INFO - Downloading blessings-1.6.tar.gz 10:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:45:58 INFO - Installing collected packages: blessings 10:45:58 INFO - Running setup.py install for blessings 10:45:58 INFO - Successfully installed blessings 10:45:58 INFO - Cleaning up... 10:45:58 INFO - Return code: 0 10:45:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:45:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:58 INFO - Reading from file tmpfile_stdout 10:45:58 INFO - Current package versions: 10:45:58 INFO - blessings == 1.6 10:45:58 INFO - blobuploader == 1.2.4 10:45:58 INFO - docopt == 0.6.1 10:45:58 INFO - functools32 == 3.2.3-2 10:45:58 INFO - jsonschema == 2.5.1 10:45:58 INFO - manifestparser == 1.1 10:45:58 INFO - mozInstall == 1.12 10:45:58 INFO - mozcrash == 1.0 10:45:58 INFO - mozdebug == 0.1 10:45:58 INFO - mozdevice == 0.50 10:45:58 INFO - mozfile == 1.2 10:45:58 INFO - mozhttpd == 0.7 10:45:58 INFO - mozinfo == 0.9 10:45:58 INFO - mozleak == 0.1 10:45:58 INFO - mozlog == 3.4 10:45:58 INFO - moznetwork == 0.27 10:45:58 INFO - mozprocess == 0.25 10:45:58 INFO - mozprofile == 0.28 10:45:58 INFO - mozrunner == 6.13 10:45:58 INFO - mozscreenshot == 0.1 10:45:58 INFO - mozsystemmonitor == 0.3 10:45:58 INFO - moztest == 0.8 10:45:58 INFO - mozversion == 1.4 10:45:58 INFO - psutil == 3.1.1 10:45:58 INFO - requests == 1.2.3 10:45:58 INFO - wsgiref == 0.1.2 10:45:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45:58 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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:45:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:58 INFO - 'HOME': '/Users/cltbld', 10:45:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:58 INFO - 'LOGNAME': 'cltbld', 10:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:58 INFO - 'MOZ_NO_REMOTE': '1', 10:45:58 INFO - 'NO_EM_RESTART': '1', 10:45:58 INFO - 'PAGER': '/bin/cat', 10:45:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:58 INFO - 'PWD': '/builds/slave/test', 10:45:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:58 INFO - 'SHELL': '/bin/bash', 10:45:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:58 INFO - 'USER': 'cltbld', 10:45:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:58 INFO - 'XPC_FLAGS': '0x0', 10:45:58 INFO - 'XPC_SERVICE_NAME': '0', 10:45:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45: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)) 10:45:59 INFO - Cleaning up... 10:45:59 INFO - Return code: 0 10:45:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:45: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 0x105569b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10556d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fadd6890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, '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.rxynXPlolY/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.w5F87QGRsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:45: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 10:45: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 10:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:45:59 INFO - 'HOME': '/Users/cltbld', 10:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:45:59 INFO - 'LOGNAME': 'cltbld', 10:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:45:59 INFO - 'MOZ_NO_REMOTE': '1', 10:45:59 INFO - 'NO_EM_RESTART': '1', 10:45:59 INFO - 'PAGER': '/bin/cat', 10:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:45:59 INFO - 'PWD': '/builds/slave/test', 10:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:45:59 INFO - 'SHELL': '/bin/bash', 10:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:45:59 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:45:59 INFO - 'USER': 'cltbld', 10:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:45:59 INFO - 'XPC_FLAGS': '0x0', 10:45:59 INFO - 'XPC_SERVICE_NAME': '0', 10:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:45:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:45:59 INFO - Cleaning up... 10:45:59 INFO - Return code: 0 10:45:59 INFO - Running post-action listener: _resource_record_post_action 10:45:59 INFO - Running post-action listener: _start_resource_monitoring 10:45:59 INFO - Starting resource monitoring. 10:45:59 INFO - [mozharness: 2017-05-14 17:45:59.571386Z] Finished create-virtualenv step (success) 10:45:59 INFO - [mozharness: 2017-05-14 17:45:59.572367Z] Running install step. 10:45:59 INFO - Running pre-action listener: _resource_record_pre_action 10:45:59 INFO - Running main action method: install 10:45:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:45:59 INFO - Reading from file tmpfile_stdout 10:45:59 INFO - Detecting whether we're running mozinstall >=1.0... 10:45:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:45:59 INFO - Reading from file tmpfile_stdout 10:45:59 INFO - Output received: 10:45:59 INFO - Usage: mozinstall [options] installer 10:45:59 INFO - Options: 10:45:59 INFO - -h, --help show this help message and exit 10:45:59 INFO - -d DEST, --destination=DEST 10:45:59 INFO - Directory to install application into. [default: 10:45:59 INFO - "/builds/slave/test"] 10:45:59 INFO - --app=APP Application being installed. [default: firefox] 10:45:59 INFO - mkdir: /builds/slave/test/build/application 10:45:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:46:20 INFO - Reading from file tmpfile_stdout 10:46:20 INFO - Output received: 10:46:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:46:20 INFO - Running post-action listener: _resource_record_post_action 10:46:20 INFO - [mozharness: 2017-05-14 17:46:20.462993Z] Finished install step (success) 10:46:20 INFO - [mozharness: 2017-05-14 17:46:20.463126Z] Running run-tests step. 10:46:20 INFO - Running pre-action listener: _resource_record_pre_action 10:46:20 INFO - Running main action method: run_tests 10:46:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:46:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:46:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:46:20 INFO - 2017-05-14 10:46:20.477 screenresolution[2708:43811] starting screenresolution argv=screenresolution get 10:46:20 INFO - 2017-05-14 10:46:20.480 screenresolution[2708:43811] Display 0: 1600x1200x32@60 10:46:20 INFO - 2017-05-14 10:46:20.486 screenresolution[2709:43814] starting screenresolution argv=screenresolution list 10:46:20 INFO - Available Modes on Display 0 10:46:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:46:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:46:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:46:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:46:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:46:20 INFO - Intel Iris: 10:46:20 INFO - Chipset Model: Intel Iris 10:46:20 INFO - Type: GPU 10:46:20 INFO - Bus: Built-In 10:46:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:46:20 INFO - Vendor: Intel (0x8086) 10:46:20 INFO - Device ID: 0x0a2e 10:46:20 INFO - Revision ID: 0x0009 10:46:20 INFO - Displays: 10:46:20 INFO - Display: 10:46:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:46:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:46:20 INFO - Display Serial Number: 1600x1200 60 10:46:20 INFO - Main Display: Yes 10:46:20 INFO - Mirror: Off 10:46:20 INFO - Online: Yes 10:46:20 INFO - Rotation: Supported 10:46:20 INFO - Return code: 0 10:46:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:46:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:46:20 INFO - Python 2.7.3 10:46:20 INFO - Return code: 0 10:46:20 INFO - grabbing minidump binary from tooltool 10:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:46:20 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 0x105279d30>, '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 0x10522ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10526ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:46:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:46:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:46:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:46:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:46:20 INFO - Return code: 0 10:46:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:46:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:46:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:46:20 INFO - ENV: RUST_BACKTRACE is now 1 10:46:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:46:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:46:20 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/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:46:20 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/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0276 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rxynXPlolY/Render', 10:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:46:20 INFO - 'HOME': '/Users/cltbld', 10:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:46:20 INFO - 'LOGNAME': 'cltbld', 10:46:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:46:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:46:20 INFO - 'MOZ_NO_REMOTE': '1', 10:46:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:46:20 INFO - 'NO_EM_RESTART': '1', 10:46:20 INFO - 'PAGER': '/bin/cat', 10:46:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:46:20 INFO - 'PWD': '/builds/slave/test', 10:46:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:46:20 INFO - 'RUST_BACKTRACE': '1', 10:46:20 INFO - 'SHELL': '/bin/bash', 10:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.w5F87QGRsp/Listeners', 10:46:20 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:46:20 INFO - 'USER': 'cltbld', 10:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:46:20 INFO - 'XPC_FLAGS': '0x0', 10:46:20 INFO - 'XPC_SERVICE_NAME': '0', 10:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:46:20 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/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:46:21 INFO - mozversion application_buildid: 20170514090926 10:46:21 INFO - mozversion application_changeset: 626efff0df630961981fc6875571fe05725f3636 10:46:21 INFO - mozversion application_display_name: Nightly 10:46:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:46:21 INFO - mozversion application_name: Firefox 10:46:21 INFO - mozversion application_remotingname: firefox 10:46:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:46:21 INFO - mozversion application_vendor: Mozilla 10:46:21 INFO - mozversion application_version: 55.0a1 10:46:21 INFO - mozversion platform_buildid: 20170514090926 10:46:21 INFO - mozversion platform_changeset: 626efff0df630961981fc6875571fe05725f3636 10:46:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:46:21 INFO - mozversion platform_version: 55.0a1 10:46:21 INFO - using testdate: 1494783981 10:46:21 INFO - actual date: 1494783981 10:46:21 INFO - starting webserver on 'localhost:60703' 10:46:21 INFO - SUITE-START | Running 2 tests 10:46:21 INFO - TEST-START | dromaeo_css 10:46:21 INFO - operating with platform_type : mac_ 10:46:21 INFO - Initialising browser for dromaeo_css test... 10:46:21 INFO - TEST-INFO | started process 2719 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpOTrIDd/profile http://localhost:60703/getInfo.html) 10:46:21 INFO - PID 2719 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 10:46:23 INFO - PID 2719 | 2017-05-14 10:46:23.533 plugin-container[2721:43995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 10:46:23 INFO - PID 2719 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:23 INFO - PID 2719 | 2017-05-14 10:46:23.536 plugin-container[2721:43995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:46:23 INFO - PID 2719 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:23 INFO - PID 2719 | 2017-05-14 10:46:23.537 plugin-container[2721:43995] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:46:23 INFO - PID 2719 | __metrics Screen width/height:1600/1200 10:46:23 INFO - PID 2719 | colorDepth:24 10:46:23 INFO - PID 2719 | Browser inner width/height: 1024/691 10:46:23 INFO - PID 2719 | __metrics 10:46:24 INFO - TEST-INFO | 2719: exit 0 10:46:24 INFO - Browser initialized. 10:46:24 INFO - Running cycle 1/1 for dromaeo_css test... 10:46:24 INFO - TEST-INFO | started process 2723 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpOTrIDd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:26 INFO - PID 2723 | 2017-05-14 10:46:26.636 plugin-container[2724:44141] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 10:46:26 INFO - PID 2723 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:26 INFO - PID 2723 | 2017-05-14 10:46:26.637 plugin-container[2724:44141] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 10:46:26 INFO - PID 2723 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:26 INFO - PID 2723 | 2017-05-14 10:46:26.637 plugin-container[2724:44141] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:46:27 INFO - PID 2723 | 2017-05-14 10:46:27.389 plugin-container[2725:44189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 10:46:27 INFO - PID 2723 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:27 INFO - PID 2723 | 2017-05-14 10:46:27.389 plugin-container[2725:44189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 10:46:27 INFO - PID 2723 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:27 INFO - PID 2723 | 2017-05-14 10:46:27.389 plugin-container[2725:44189] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:46:36 INFO - PID 2723 | RSS: Main: 236249088 10:46:36 INFO - PID 2723 | 10:49:40 INFO - PID 2723 | Cycle 1(1): loaded http://localhost:60703/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60703/tests/dromaeo/cssquery-ext.html) 10:49:42 INFO - PID 2723 | RSS: Main: 256716800 10:49:42 INFO - PID 2723 | 10:52:44 INFO - PID 2723 | Cycle 1(1): loaded http://localhost:60703/tests/dromaeo/cssquery-ext.html (next: http://localhost:60703/tests/dromaeo/cssquery-jquery.html) 10:52:45 INFO - PID 2723 | RSS: Main: 265383936 10:52:45 INFO - PID 2723 | 10:55:46 INFO - PID 2723 | Cycle 1(1): loaded http://localhost:60703/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60703/tests/dromaeo/cssquery-mootools.html) 10:55:47 INFO - PID 2723 | RSS: Main: 269021184 10:55:47 INFO - PID 2723 | 10:58:49 INFO - PID 2723 | Cycle 1(1): loaded http://localhost:60703/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60703/tests/dromaeo/cssquery-prototype.html) 10:58:49 INFO - PID 2723 | RSS: Main: 271945728 10:58:49 INFO - PID 2723 | 11:01:52 INFO - PID 2723 | Cycle 1(1): loaded http://localhost:60703/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60703/tests/dromaeo/cssquery-yui.html) 11:01:53 INFO - PID 2723 | RSS: Main: 273457152 11:01:53 INFO - PID 2723 | 11:04:54 INFO - PID 2723 | Cycle 1(1): loaded http://localhost:60703/tests/dromaeo/cssquery-yui.html (next: http://localhost:60703/tests/dromaeo/cssquery-dojo.html) 11:04:55 INFO - PID 2723 | RSS: Main: 274747392 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | __start_tp_report 11:04:55 INFO - PID 2723 | _x_x_mozilla_page_load 11:04:55 INFO - PID 2723 | _x_x_mozilla_page_load_details 11:04:55 INFO - PID 2723 | |i|pagename|runs| 11:04:55 INFO - PID 2723 | |0;dojo.html;3524.436090225566;3555.1289933966177;3577.2130131371105;3581.3911635022046;3586.3903136466797;5926.555508336875;6124.724387402566;6234.127222188898;6234.283057448395;6258.874822503547;5397.839464515128;5928.347881733006;6161.045038019114;6180.84547886302;6194.442500174983;5386.138217885139;5509.807156749514;5536.972315138419;5570.498655121039;5669.716514174295;4676.69601475903;5481.424450432707;5534.584906132036;5562.054450743383;5634.661920284776;4914.631402644816;5549.528290095326;5590.329160500725;5633.549316054727;5647.068233741448;4526.411566496367;5400.729963501848;5470.288862447856;5534.363548191929;5563.554915606742;5501.9174712379345;5517.420670829545;5530.198121272433;5530.806421775259;5592;6038.27540695115;6254.21822272216;6273.592216505913;6279.995200767855;6289.622622642611;6430.099786029959;6493.902591461132;6607.636579988099;6737.3936345728625;6758.628275444898;6795.1845778506895;6799.558028728117;6808.863822723518;6820.522563420563;6823.249442561314;6314.93685063148;6322.620642761449;6329.841753956188;6347.1114044033875;6369.872602547923;6661.833454163685;6888.724451022003;6905.1713794345;6922.203946546211;6950.8609827804175;6498.447631951242;6583.3416658334745;6588.374104460068;6594.637294948824;6631.602103873783;6732.932670673328;6774.45804335652;6802.8299292517095;6809.489288303397;6820.931790682129;6403.711832967528;6474.320196379406;6513.967430162819;6660.367265109807;6674.399304062561;5871.442212989759;6621.238557565845;6678.198616165994;6686.598804071771;6727.394534491919;5336.199570064521;5339.5728341732565;5351.57187425005;5354.170103633943;5356.276902618175;4695.43654761424;4732.668713190113;4747.406574178228;4764.237721964469;4780.11567859947;6218.9378106219265;6303.243610766737;6337.081123237105;6337.873242535124;6353.3329000455215;6775.322467753185;6786.3892249697765;6821.658917054227;6833.726650933907;6848;6354.555181137369;6358.300586935434;6370.968145159244;6376.808695739135;6397.296297407282;5755.740991655385;5829.387914268941;5848.941510584925;5874.970625146847;5946.375630558816;6799.388055074968;6828.146481689789;6934.757283495053;6963.059986901703;6991.8951215730785;6752.561083529555;6810.114685090906;6810.5913645181445;6823.590584564942;6853.109095817711;6942.201646810479;6949.895751563832;6958.165020197609;6969.337912898267;7001.964990175016;6465.935340646722;6543.443807276435;6574.868502629825;6657.567258128206;6699.899501507384;6662.433693136138;6739.898901516383;6765.748336557753;6807.421369183675;6810.284920083518;6502.187226596676;6538.444232240313;6591.110200123114;6591.868162636624;6606;6338.778142764981;6437;6529.706163222571;6575.27671956094;6617.43751781104;6332.113504109337;6342.619442833445;6465.967670161619;6488.870222595616;6498;6360.109584658059;6445.097686324012;6492.35076492347;6497.31778163305;6596.076549283201;5572.888542229214;6302.149209856794;6349.587276826992;6395.808125756234;6419.358064193543 11:04:55 INFO - PID 2723 | |1;ext.html;803.3773825285404;832.6710949175078;1135.34717537416;1153.0832987774713;1286.1061562214254;37260.813695931516;38127.428088578694;38135.427968580494;38147.618523814715;38223;38164.23671526568;38572.61427385725;38630.84107476773;38633.61366386342;38722.4191637125;7681.731139410122;7857.567833769154;8014.3187829034605;8610.268127209163;8662.177093176138;8064.556449395281;8116.391270654695;8600.311975042014;8691.95654021729;8696.521691306976;8662.956685216564;8704.956475217647;8706.434081784695;8714.520701361422;8737.519436430994;7606.163322034572;8491.872621910676;8679.869801952944;8699.347548933823;8716;8439.789005274855;8477.915220847835;8485.533295668736;8529.74410767678;8535;37303.62696373029;37343.879683609266;37581;37638.43542346867;37694;3617.855285788565;3647;3655.94516082259;3656.3601369760186;3659.451082337671;30529.847350763102;30811.537826932614;30822.229444264074;30834.53748193779;30846.691533084606;3121.6566177720433;3140.0736782648946;3161.8577164027674;3168.683131686836;3174.4444722173525;306498;307057;308447.91552084417;308499.4575027133;309269;44905.326420103724;47499.525004750205;47655.76172119186;47695.28457073147;48377.5162248373;49110.50889491059;49380.765480863265;49468.257976130386;49572.0085598286;49587.289207525224;14095.577132686036;14159.575212743634;14167.504137355143;14170.008099433147;14179.716405672036;11257.268277561932;11261.493232804543;11271.323720576627;11276.718082048015;11311.208215425006;13124.278164700841;13124.343782810822;13127.868721312856;13147.013973951993;13258;5704.543636509069;5717.6551717245875;5760.94239057604;5789.392113828072;5790.247267855236;1005.6128390569684;1044.4673216659558;1045.8169820281573;1072.5870539842217;1083.106437189322;21365;24409;24649.507009860063;24771.13301034455;24782.132625358026;991.6231831903831;1125.6679279612533;1154.1343992005995;1161.7269941563725;1173.6596387047832;12566.931810796003;12572.559960401342;12648.114631975857;12652.291940588088;12682.429290681941;305935.47032264696;306163;307510;307677;308278;52976;53148.46851531435;53252.467475325524;53262.733686332096;53304.46695532995;1540.4454396417282;1545.250553481583;1574.7952766140559;1582.185174635064;1584.382090984517;24074.712902274256;24182.395440113432;24334;24339.39151521226;24380.75619243785;37464.250714985;37554.62445375511;37564.060898476666;37660.62339376571;37762.86711398663;266083;267183;269385;270088;270696;28979.84080636751;30569.388612228075;30984.380312394078;33616.32767344591;34945.650543495256;40254.59745402508;42887.78556107199;45205.32192017188;45206;45387.86530336768;29208.26979325534;30197.547036794906;30737.538936915516;30938.226544336572;31374.52938205883;30169.547456787728;30224.395512090075;30815.845920770254;31035.53446698256;31110.22224444407;37133.81433092817;37256.81371593125;37317.44023839699;37347.62652373441;37391;25738.613920791577;29263.85368073231;29613.59443664006;30559.388812224075;31138.37723245477;22471.43821404478;22558;23576.410589734707;24209.884319665347;24710.629340560266 11:04:55 INFO - PID 2723 | |2;jquery.html;406.87183537185786;427.6471910673695;429.10959758501116;434.8977990172309;461.1675924955456;19342.226310947546;21029.894850525747;21788.782112178873;21900.342989710294;22039.889800551016;21584.568308633818;22783.772162278372;23011;23160.536789264206;23185.768142318615;6036.547258955585;6169.629822210682;6224.0663900415075;6240.8439789005415;6251.531135164858;4889.902201955959;6082.452579267865;6142.447179753844;6146.66193359365;6180.81457556274;5852.619579727326;6050.606710563821;6105.847353816168;6124.142620033199;6128.448439640431;6002.729877155517;6106.572539922207;6143.139960405524;6146.262448506163;6157.138000679908;5383.2194331821665;5397.271368365299;5449.373322067973;5455.345358556999;5480.945190548084;26982.73017269822;27171.1848644541;27326.45347093047;28549.714502854913;28567.857160714273;2244.2930476899724;2305.296884450242;2319.060780383947;2327.8836058197194;2334.5447637710654;14709.338079786436;17869.910650446796;18194.727079093824;18250.361237356763;18526.629467410712;2115.1962254343407;2119.9152033918626;2145.817605503534;2152.13914434226;2159.2982280758815;140893;174976.12511937358;175050;175915;177466;12602.747945041048;12659.556915507914;12761.553345632858;12794.23234605908;13000.414981325863;16220.756688649679;16655.00069995804;16680.165991700367;16689.66620667604;16751.162441877856;7600.771976840703;8738.432001919982;9014.59434325457;9042.909570904338;9172.6789562365;8582.527960962207;8605.440646357967;8605.956970215235;8806.471611703319;8808;7849.293563579306;7911;7932.365410767125;7998.80002999918;8016.959915200387;2140.839437042228;2151.4943988162768;2154.6083846929896;2161.2867753641262;2317.629179331299;939.7313626604166;962.1965658675085;980.1766516126488;1007.7128018514688;1026.7279171019686;21051.68422473664;21298.680519792215;21385.89307053455;21593.35219943404;21788.56422871534;990.3018372047833;1016.7813920007234;1034.8654674892366;1045.482486169351;1053.657561292583;10401.075800976843;10946.890531094747;10989.767219197058;11119.110471162288;13254.73490530184;550365;553900;560434;560631;563020;117485;128283.71716282904;131523.55955505563;133738.6261373855;136376;1059.2955684469896;1240.3612139748218;1252.348778635123;1256.1709271880516;1265.943032563537;18090.819091808913;18563.81436185675;21644.67532987038;21890.23384181546;22562.210322638628;56298.43701562932;57434.851302974545;57706;58603.70698146652;58873;73190.63404682942;77780.61109694415;78316.60841695755;79830.80253796082;80258;8459;8596.640199422995;8743.125687431206;9196.908030919873;9332.440053596805;13423.731525369632;13988.37052332668;13990.440382384591;14043.297835108408;14487.275636217935;11759.353235572125;11963.940180299043;11976.76046479083;11981.28112313264;12081.75836483283;10332.638357657446;10648.988346107417;10913;11312.83030754523;11577.363245021605;10240.692779216635;11426.628804543509;11961.581344652895;12074.577389791315;12174.269543827399;7423;7736.994190755391;8868.15752503511;8918.73243802687;8993.370464067453;11862.762744744885;12006.939965300118;12126.818097728365;12127.393630318626;12166.939165304117 11:04:55 INFO - PID 2723 | |3;mootools.html;1268.9238645681257;1471.5585324402678;1475.43933305344;1478.186997151567;1479.9038062525935;11763;11951.521939122447;12224.144309898289;12324.568640097586;12331.691707707312;11877.76244475112;12081.456334464949;12082.637520874388;12094;12101;2580.896764129437;2614.215735279418;2630.447606002742;2630.5791073428254;2632.4735052989477;2138.593667203234;2535.5816290312077;2587.5471792436247;2591.235585502272;2591.4557942831934;2559.4753075619456;2559.820812543123;2602.10227471522;2605.0361366294496;2615.0324379979434;2533.1640558615613;2558.6417901493714;2576.9742302577015;2587.650667159928;2588.2235329401105;2135.1993002624017;2205.724284464442;2513.3842208658994;2563.1797824696105;2580.909668161605;11490.942545287304;11866.644000679993;11869.46587403569;11871.703207419798;11888.28670279786;6623.3376662333385;6861.279565645636;6880.862382752317;6881.174259088892;6907.171139463247;3983.521977362706;4272.33778764292;4380.970471939092;4393.6045755881805;4412.227860124465;1230.138902768066;1246.569933372985;1264.4689230523202;1284.1396264502805;1286.6847622332496;16489.752653710202;16512.174391280387;16602.418915338025;16682.416115436023;16687.499375018768;5429.837104886859;5500.614956952975;5580.720963951786;5739.426057394311;5776.33572139201;11763.705907352214;11883.821742673694;11963.64109076729;11971.580994665144;11971.64085077449;6506.60960342381;6545.934540654628;6643.5017373697165;6650.70071846768;6656.600603963778;6136.662483563447;6186.505079593621;6211.565190436717;6288.213973253343;6298.464630506458;4268.765217912983;4436.00189957257;4451.109778044404;4481.058977614673;4516.525764794715;1018.5162048027128;1056.9418681972566;1103.5309993252836;1112.2603468693228;1322.418136020148;808.7775861638097;815.5392203405057;835.5571547080058;844.5566077809076;868.3574155125162;6307.306196318401;6331.303556608769;6411.326810684905;6448.613083215022;6529.412352888168;587.9059350503899;603.3001718007091;612.5161122713096;613.871087071711;614.8186285045922;3336.566246387954;3822.6559609635265;4050.4936882889638;4395.208862404734;4828.285958484757;14895.627609309637;15361.385544578316;15416.46042388511;15483.922580387027;15537.84462155364;13824.585262441942;14200;14901.180435075958;15365.308561114776;15390.692186156439;8535;8610.913890861137;8668.133186681282;8798.736037919001;8829.293656507463;6949.861002779815;7204.135503739585;7366.373858222111;7383.114026316876;7407;13543.984201185147;13571.321433928066;13720.65698357549;13752.65618359549;13772.380242888894;10799.730006749895;13837.584872453843;13860.930695346458;14040.78938815939;14338.354774035402;8311.667533298842;8404.117567655308;8519.148085191431;8519.48883067018;8520.19058189471;10774.138068954622;10835.891641083488;10865.565377384815;10933.781324373309;11027.448627568428;9287.72136835896;9331.206847418045;9354.859677104985;9418.529073546433;9473;9139.497327647043;9147;9156.816863662556;9201.033891441302;9248.861267080865;8325.167483251626;8356.373272004503;8498.362622803437;8508.702195423131;8526.701565445179;5586.86032849182;5981.192539007352;6070.696465176812;6118.724657390339;6286.245650521967;6194.938050619436;6309.148264984168;6386.840328991812;6452.064450654722;6530.738770449128 11:04:55 INFO - PID 2723 | |4;prototype.html;369.4089456869012;395.6735693053231;401.18158955730314;403.74564024664465;414.78845788647783;5711.685857277848;5731.45551172639;5946.3161736400325;6299.0866324383;6314.40013198746;6008.909866352008;6082.239720034996;6138.723757430916;6140.526273694315;6182.876342473148;4383.824647014116;4682.180618391776;4688.203005489075;4705.270683044134;4713.882152946169;3710.239400922804;3859.671927886133;3931.7640941543455;4426.800793964263;4699.67102302839;3598.6581274778987;4318.611324980751;4324.243257429953;4613.723176609415;4647.88380290492;4062.634362907323;4177.979110104445;4641.28272539161;4647.279671650899;4704.717716936961;3593.74765836918;3776.206996530732;3946.565877753445;4135.97932010341;4151.211269858717;5270.631055826094;5517.227588137664;5966.971229781851;5982.820515384546;6066.877627638901;4798.616110711135;4938.530839570235;5499.532539734128;5553.500184983371;5564.944350556483;5406.972965135149;6308.413550444342;6366.949453340193;6650.634215118166;6660.733570657168;4127.7936103195025;4375.124975005012;4394.4067550880545;4639.976800116012;4711.670183087185;6859.279775623538;6976.825579360506;6988.336108069725;7006.684699188549;7088.475977664827;4037.3338399164395;4378.233809083461;4389.539098394686;4392.824287028483;4406.700023493077;3716.4053751399647;4461.57615026572;4473.597376236155;4484.68607197493;4628;4413.3159360299205;4594.805350608865;5304.655197412156;5347.973260133675;5370.898965712097;5569.526590239875;6487.6756162191705;6676.699548520328;6705.329467053353;6712.932870671329;1509.456595625574;1529.7399442094647;1532.5862017255279;1537.2928452911563;1538;996.8903420623726;1000.2295936192897;1000.7047920863363;1005.7435353984687;1025.876894772617;6333.90499142504;6360.300366959631;6361.543033820676;6391.201099862517;6428.332242119562;5567.47109024642;5666.150077488327;6313.463355614732;6339.714712837934;6943.513954023271;4834.903301933941;5116.386033675908;6303.172080096812;6386.169797926333;6410;3313.7680362374886;3590.56913170416;3617.4935509028755;3623.836927338276;3685.447182922583;5633.352164501053;6331.06902394774;6566;7013.614251216232;7017.824554386181;6205.851917395296;6670.232923213893;7125.465590080765;7130.25132361105;7155.749548765768;5779.566532509994;5973.492253158443;6987.637410704965;7139.892901606584;7210.466871233058;5812.309183668485;6836.495970886397;6908.930910690829;6925.238223795278;6932.792016239521;6233.407826256498;6255.40573645503;6769.526133170631;6823.215330237086;6911.447084233148;6192.907106393498;6968.512204145661;7169.426445884213;7212.71149154028;7233.31283528064;4469.284914413678;4476.888077798081;5067.670601410897;5079.034983353151;5101.9744901276745;5636.830895073154;6808.829779255559;6863.656817159222;6986.685599147949;7044.506884518035;6738.629375384205;6740.595564266159;6744.393004629607;6763.560368576027;6768.424683901923;5663.745131469176;5686.488216060575;6367.204099487564;6412.454941330039;6724.4284235840505;6525.282218956006;6542.238213027433;6559.6064236145985;6604.438622717123;6621.212272686444;5216.869578260574;5763.636908867345;6210.664707087998;6549.672516374258;6707.295733947865;5029.547340739351;5752.942470575241;6303.905441418291;6479.1901012373455;6595.142631458071 11:04:55 INFO - PID 2723 | |5;yui.html;718.9029481020062;777.265484117509;822.0792712162372;853.7609469348585;865.1219012702109;13494.662633434169;16333.75499367509;16601.50195494134;18319;18927.52681182971;17388.826111738912;17925.91037044816;17958.281668733267;18398.632027359447;18595.163217655205;978.5107446276861;995.7261753017906;997.3915911294105;1001.2790790630734;1004.3070281505737;959.1176117971448;996.8884693566657;1020.8723909511311;1022.3508072374059;1025.8153532364172;1074.6722249713835;1076.0692001418795;1081.3403823667595;1082.3668154129844;1088.0751361342875;1053.031211285619;1056.2342301831188;1066.221658189522;1066.807974564578;1076.983845242322;765.6439755513713;768.5119948832476;768.7924260449647;779.2129948751744;785.6229010075139;15842.683146337124;16260.75608865868;17271;17471.475855724348;17492.300307987665;17332.653346933115;17382.3047078118;17577.945323280645;17684;17840.286388544442;14294.428222871073;14489.42042318306;14584.927075364662;14639.853601464061;14646.780298295535;3641.2353405784816;3647.6717095461536;3657.213699054716;3687.317846198435;3690.446433035066;99141;115350.84649153569;122985;123199;125184.74815251913;13755.518556850462;13857.030007899551;13899.16605003703;13979;14043.929780351033;25439.491210175693;26083.347916302246;26115.347116322246;26226.344341391236;26276.21171364862;7893.921060789318;7996.0804507481225;8004.399670024655;8024.117347091816;8026.7591972240925;3893.0267433141717;3914.5124852555846;3917.353636649978;3941.428492868518;3970.3448930926074;4354.869353919387;4421.668374871898;4460.933086003713;4462.085272519126;4468.597826195658;928.6935311347239;963.8746962894779;967.086103632075;976.028851292968;976.9218462522981;663.8771827211981;668.5453891353828;673.9258681545026;681.6693903456819;699.78656509765;10678.572857085785;11125.276857004268;11745.481456463569;11942.104342174373;12026.699332516582;643.8551325951623;660.9768658096943;668.0713807807152;676.1075380497795;698.543536725922;4861.29511220871;4871.952530205952;4900.313956046157;4956.4547899731;5054.671446355975;70771;80515.59742201252;86433.13566864376;91229.54385228032;99133;89399.55300223458;91071.54464227769;91357;91926;93136;56825.43174568201;76692;77750.83373749399;83935;84881.72677409844;11024.834627480755;11147.996680298487;11545.653630391102;11571.13216508782;11582.015528680156;41673.541426049946;41781.16437671169;41894.37158442565;42120;42306.7307980761;54429;54642.180367293724;54950;55291;55642.443575563724;14355;16618.58453538671;17455.56361090983;17473.82526174722;17480.30078796834;17814.643707126044;18087;18138.093095345444;18706.345277915207;18920.243190272784;15643.921780391025;16626.58433539171;16707.331706731595;17505.912470438077;17847.643047139245;16361.427350042692;17141.57146071358;17350.13249337503;17568.912155439142;17587.736183957506;16177.838221618103;16205.351785928431;16850.57873553122;17186.484405467854;17214.65570688554;11733.237339572901;11782;11855.229410088661;12261.203021803554;12342;10416.345986742712;13728.753417194952;13740.725185496034;13750.243736594583;13827.79258311106 11:04:55 INFO - PID 2723 | __end_tp_report 11:04:55 INFO - PID 2723 | __start_cc_report 11:04:55 INFO - PID 2723 | _x_x_mozilla_cycle_collect,337 11:04:55 INFO - PID 2723 | __end_cc_report 11:04:55 INFO - PID 2723 | __startTimestamp1494785095144__endTimestamp 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | ------- Summary: start ------- 11:04:55 INFO - PID 2723 | Number of tests: 6 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | [#0] dojo.html Cycles:165 Average:6176.32 Median:6395.81 stddev:741.36 (11.6%) stddev-sans-first:713.84 11:04:55 INFO - PID 2723 | Values: 3524.4 3555.1 3577.2 3581.4 3586.4 5926.6 6124.7 6234.1 6234.3 6258.9 5397.8 5928.3 6161.0 6180.8 6194.4 5386.1 5509.8 5537.0 5570.5 5669.7 4676.7 5481.4 5534.6 5562.1 5634.7 4914.6 5549.5 5590.3 5633.5 5647.1 4526.4 5400.7 5470.3 5534.4 5563.6 5501.9 5517.4 5530.2 5530.8 5592.0 6038.3 6254.2 6273.6 6280.0 6289.6 6430.1 6493.9 6607.6 6737.4 6758.6 6795.2 6799.6 6808.9 6820.5 6823.2 6314.9 6322.6 6329.8 6347.1 6369.9 6661.8 6888.7 6905.2 6922.2 6950.9 6498.4 6583.3 6588.4 6594.6 6631.6 6732.9 6774.5 6802.8 6809.5 6820.9 6403.7 6474.3 6514.0 6660.4 6674.4 5871.4 6621.2 6678.2 6686.6 6727.4 5336.2 5339.6 5351.6 5354.2 5356.3 4695.4 4732.7 4747.4 4764.2 4780.1 6218.9 6303.2 6337.1 6337.9 6353.3 6775.3 6786.4 6821.7 6833.7 6848.0 6354.6 6358.3 6371.0 6376.8 6397.3 5755.7 5829.4 5848.9 5875.0 5946.4 6799.4 6828.1 6934.8 6963.1 6991.9 6752.6 6810.1 6810.6 6823.6 6853.1 6942.2 6949.9 6958.2 6969.3 7002.0 6465.9 6543.4 6574.9 6657.6 6699.9 6662.4 6739.9 6765.7 6807.4 6810.3 6502.2 6538.4 6591.1 6591.9 6606.0 6338.8 6437.0 6529.7 6575.3 6617.4 6332.1 6342.6 6466.0 6488.9 6498.0 6360.1 6445.1 6492.4 6497.3 6596.1 5572.9 6302.1 6349.6 6395.8 6419.4 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | [#1] ext.html Cycles:180 Average:44496.22 Median:17257.30 stddev:77339.41 (448.2%) stddev-sans-first:77486.79 11:04:55 INFO - PID 2723 | Values: 803.4 832.7 1135.3 1153.1 1286.1 37260.8 38127.4 38135.4 38147.6 38223.0 38164.2 38572.6 38630.8 38633.6 38722.4 7681.7 7857.6 8014.3 8610.3 8662.2 8064.6 8116.4 8600.3 8692.0 8696.5 8663.0 8705.0 8706.4 8714.5 8737.5 7606.2 8491.9 8679.9 8699.3 8716.0 8439.8 8477.9 8485.5 8529.7 8535.0 37303.6 37343.9 37581.0 37638.4 37694.0 3617.9 3647.0 3655.9 3656.4 3659.5 30529.8 30811.5 30822.2 30834.5 30846.7 3121.7 3140.1 3161.9 3168.7 3174.4 306498.0 307057.0 308447.9 308499.5 309269.0 44905.3 47499.5 47655.8 47695.3 48377.5 49110.5 49380.8 49468.3 49572.0 49587.3 14095.6 14159.6 14167.5 14170.0 14179.7 11257.3 11261.5 11271.3 11276.7 11311.2 13124.3 13124.3 13127.9 13147.0 13258.0 5704.5 5717.7 5760.9 5789.4 5790.2 1005.6 1044.5 1045.8 1072.6 1083.1 21365.0 24409.0 24649.5 24771.1 24782.1 991.6 1125.7 1154.1 1161.7 1173.7 12566.9 12572.6 12648.1 12652.3 12682.4 305935.5 306163.0 307510.0 307677.0 308278.0 52976.0 53148.5 53252.5 53262.7 53304.5 1540.4 1545.3 1574.8 1582.2 1584.4 24074.7 24182.4 24334.0 24339.4 24380.8 37464.3 37554.6 37564.1 37660.6 37762.9 266083.0 267183.0 269385.0 270088.0 270696.0 28979.8 30569.4 30984.4 33616.3 34945.7 40254.6 42887.8 45205.3 45206.0 45387.9 29208.3 30197.5 30737.5 30938.2 31374.5 30169.5 30224.4 30815.8 31035.5 31110.2 37133.8 37256.8 37317.4 37347.6 37391.0 25738.6 29263.9 29613.6 30559.4 31138.4 22471.4 22558.0 23576.4 24209.9 24710.6 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | [#2] jquery.html Cycles:180 Average:36295.09 Median:12403.25 stddev:95033.21 (766.2%) stddev-sans-first:95261.60 11:04:55 INFO - PID 2723 | Values: 406.9 427.6 429.1 434.9 461.2 19342.2 21029.9 21788.8 21900.3 22039.9 21584.6 22783.8 23011.0 23160.5 23185.8 6036.5 6169.6 6224.1 6240.8 6251.5 4889.9 6082.5 6142.4 6146.7 6180.8 5852.6 6050.6 6105.8 6124.1 6128.4 6002.7 6106.6 6143.1 6146.3 6157.1 5383.2 5397.3 5449.4 5455.3 5480.9 26982.7 27171.2 27326.5 28549.7 28567.9 2244.3 2305.3 2319.1 2327.9 2334.5 14709.3 17869.9 18194.7 18250.4 18526.6 2115.2 2119.9 2145.8 2152.1 2159.3 140893.0 174976.1 175050.0 175915.0 177466.0 12602.7 12659.6 12761.6 12794.2 13000.4 16220.8 16655.0 16680.2 16689.7 16751.2 7600.8 8738.4 9014.6 9042.9 9172.7 8582.5 8605.4 8606.0 8806.5 8808.0 7849.3 7911.0 7932.4 7998.8 8017.0 2140.8 2151.5 2154.6 2161.3 2317.6 939.7 962.2 980.2 1007.7 1026.7 21051.7 21298.7 21385.9 21593.4 21788.6 990.3 1016.8 1034.9 1045.5 1053.7 10401.1 10946.9 10989.8 11119.1 13254.7 550365.0 553900.0 560434.0 560631.0 563020.0 117485.0 128283.7 131523.6 133738.6 136376.0 1059.3 1240.4 1252.3 1256.2 1265.9 18090.8 18563.8 21644.7 21890.2 22562.2 56298.4 57434.9 57706.0 58603.7 58873.0 73190.6 77780.6 78316.6 79830.8 80258.0 8459.0 8596.6 8743.1 9196.9 9332.4 13423.7 13988.4 13990.4 14043.3 14487.3 11759.4 11963.9 11976.8 11981.3 12081.8 10332.6 10649.0 10913.0 11312.8 11577.4 10240.7 11426.6 11961.6 12074.6 12174.3 7423.0 7737.0 8868.2 8918.7 8993.4 11862.8 12006.9 12126.8 12127.4 12166.9 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | [#3] mootools.html Cycles:180 Average:7185.02 Median:3579.61 stddev:4557.65 (127.3%) stddev-sans-first:4548.75 11:04:55 INFO - PID 2723 | Values: 1268.9 1471.6 1475.4 1478.2 1479.9 11763.0 11951.5 12224.1 12324.6 12331.7 11877.8 12081.5 12082.6 12094.0 12101.0 2580.9 2614.2 2630.4 2630.6 2632.5 2138.6 2535.6 2587.5 2591.2 2591.5 2559.5 2559.8 2602.1 2605.0 2615.0 2533.2 2558.6 2577.0 2587.7 2588.2 2135.2 2205.7 2513.4 2563.2 2580.9 11490.9 11866.6 11869.5 11871.7 11888.3 6623.3 6861.3 6880.9 6881.2 6907.2 3983.5 4272.3 4381.0 4393.6 4412.2 1230.1 1246.6 1264.5 1284.1 1286.7 16489.8 16512.2 16602.4 16682.4 16687.5 5429.8 5500.6 5580.7 5739.4 5776.3 11763.7 11883.8 11963.6 11971.6 11971.6 6506.6 6545.9 6643.5 6650.7 6656.6 6136.7 6186.5 6211.6 6288.2 6298.5 4268.8 4436.0 4451.1 4481.1 4516.5 1018.5 1056.9 1103.5 1112.3 1322.4 808.8 815.5 835.6 844.6 868.4 6307.3 6331.3 6411.3 6448.6 6529.4 587.9 603.3 612.5 613.9 614.8 3336.6 3822.7 4050.5 4395.2 4828.3 14895.6 15361.4 15416.5 15483.9 15537.8 13824.6 14200.0 14901.2 15365.3 15390.7 8535.0 8610.9 8668.1 8798.7 8829.3 6949.9 7204.1 7366.4 7383.1 7407.0 13544.0 13571.3 13720.7 13752.7 13772.4 10799.7 13837.6 13860.9 14040.8 14338.4 8311.7 8404.1 8519.1 8519.5 8520.2 10774.1 10835.9 10865.6 10933.8 11027.4 9287.7 9331.2 9354.9 9418.5 9473.0 9139.5 9147.0 9156.8 9201.0 9248.9 8325.2 8356.4 8498.4 8508.7 8526.7 5586.9 5981.2 6070.7 6118.7 6286.2 6194.9 6309.1 6386.8 6452.1 6530.7 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | [#4] prototype.html Cycles:180 Average:5294.19 Median:5758.29 stddev:1677.01 (29.1%) stddev-sans-first:1640.47 11:04:55 INFO - PID 2723 | Values: 369.4 395.7 401.2 403.7 414.8 5711.7 5731.5 5946.3 6299.1 6314.4 6008.9 6082.2 6138.7 6140.5 6182.9 4383.8 4682.2 4688.2 4705.3 4713.9 3710.2 3859.7 3931.8 4426.8 4699.7 3598.7 4318.6 4324.2 4613.7 4647.9 4062.6 4178.0 4641.3 4647.3 4704.7 3593.7 3776.2 3946.6 4136.0 4151.2 5270.6 5517.2 5967.0 5982.8 6066.9 4798.6 4938.5 5499.5 5553.5 5564.9 5407.0 6308.4 6366.9 6650.6 6660.7 4127.8 4375.1 4394.4 4640.0 4711.7 6859.3 6976.8 6988.3 7006.7 7088.5 4037.3 4378.2 4389.5 4392.8 4406.7 3716.4 4461.6 4473.6 4484.7 4628.0 4413.3 4594.8 5304.7 5348.0 5370.9 5569.5 6487.7 6676.7 6705.3 6712.9 1509.5 1529.7 1532.6 1537.3 1538.0 996.9 1000.2 1000.7 1005.7 1025.9 6333.9 6360.3 6361.5 6391.2 6428.3 5567.5 5666.2 6313.5 6339.7 6943.5 4834.9 5116.4 6303.2 6386.2 6410.0 3313.8 3590.6 3617.5 3623.8 3685.4 5633.4 6331.1 6566.0 7013.6 7017.8 6205.9 6670.2 7125.5 7130.3 7155.7 5779.6 5973.5 6987.6 7139.9 7210.5 5812.3 6836.5 6908.9 6925.2 6932.8 6233.4 6255.4 6769.5 6823.2 6911.4 6192.9 6968.5 7169.4 7212.7 7233.3 4469.3 4476.9 5067.7 5079.0 5102.0 5636.8 6808.8 6863.7 6986.7 7044.5 6738.6 6740.6 6744.4 6763.6 6768.4 5663.7 5686.5 6367.2 6412.5 6724.4 6525.3 6542.2 6559.6 6604.4 6621.2 5216.9 5763.6 6210.7 6549.7 6707.3 5029.5 5752.9 6303.9 6479.2 6595.1 11:04:55 INFO - PID 2723 | 11:04:55 INFO - PID 2723 | [#5] yui.html Cycles:180 Average:21043.48 Median:18650.75 stddev:28365.73 (152.1%) stddev-sans-first:28404.25 11:04:55 INFO - PID 2723 | Values: 718.9 777.3 822.1 853.8 865.1 13494.7 16333.8 16601.5 18319.0 18927.5 17388.8 17925.9 17958.3 18398.6 18595.2 978.5 995.7 997.4 1001.3 1004.3 959.1 996.9 1020.9 1022.4 1025.8 1074.7 1076.1 1081.3 1082.4 1088.1 1053.0 1056.2 1066.2 1066.8 1077.0 765.6 768.5 768.8 779.2 785.6 15842.7 16260.8 17271.0 17471.5 17492.3 17332.7 17382.3 17577.9 17684.0 17840.3 14294.4 14489.4 14584.9 14639.9 14646.8 3641.2 3647.7 3657.2 3687.3 3690.4 99141.0 115350.8 122985.0 123199.0 125184.7 13755.5 13857.0 13899.2 13979.0 14043.9 25439.5 26083.3 26115.3 26226.3 26276.2 7893.9 7996.1 8004.4 8024.1 8026.8 3893.0 3914.5 3917.4 3941.4 3970.3 4354.9 4421.7 4460.9 4462.1 4468.6 928.7 963.9 967.1 976.0 976.9 663.9 668.5 673.9 681.7 699.8 10678.6 11125.3 11745.5 11942.1 12026.7 643.9 661.0 668.1 676.1 698.5 4861.3 4872.0 4900.3 4956.5 5054.7 70771.0 80515.6 86433.1 91229.5 99133.0 89399.6 91071.5 91357.0 91926.0 93136.0 56825.4 76692.0 77750.8 83935.0 84881.7 11024.8 11148.0 11545.7 11571.1 11582.0 41673.5 41781.2 41894.4 42120.0 42306.7 54429.0 54642.2 54950.0 55291.0 55642.4 14355.0 16618.6 17455.6 17473.8 17480.3 17814.6 18087.0 18138.1 18706.3 18920.2 15643.9 16626.6 16707.3 17505.9 17847.6 16361.4 17141.6 17350.1 17568.9 17587.7 16177.8 16205.4 16850.6 17186.5 17214.7 11733.2 11782.0 11855.2 12261.2 12342.0 10416.3 13728.8 13740.7 13750.2 13827.8 11:04:55 INFO - PID 2723 | -------- Summary: end -------- 11:04:55 INFO - PID 2723 | 11:04:55 INFO - TEST-INFO | 2723: exit 0 11:04:55 INFO - TEST-OK | dromaeo_css | took 1114701ms 11:04:55 INFO - TEST-START | kraken 11:04:55 INFO - operating with platform_type : mac_ 11:04:55 INFO - Initialising browser for kraken test... 11:04:55 INFO - TEST-INFO | started process 2731 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmph4pt1K/profile http://localhost:60703/getInfo.html) 11:04:56 INFO - PID 2731 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 11:04:58 INFO - PID 2731 | 2017-05-14 11:04:58.274 plugin-container[2732:46386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 11:04:58 INFO - PID 2731 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:58 INFO - PID 2731 | 2017-05-14 11:04:58.278 plugin-container[2732:46386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990f, name = 'com.apple.CFPasteboardClient' 11:04:58 INFO - PID 2731 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:58 INFO - PID 2731 | 2017-05-14 11:04:58.278 plugin-container[2732:46386] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:04:58 INFO - PID 2731 | __metrics Screen width/height:1600/1200 11:04:58 INFO - PID 2731 | colorDepth:24 11:04:58 INFO - PID 2731 | Browser inner width/height: 1024/691 11:04:58 INFO - PID 2731 | __metrics 11:04:58 INFO - PID 2731 | 2017-05-14 11:04:58.882 plugin-container[2733:46439] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 11:04:58 INFO - PID 2731 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:58 INFO - PID 2731 | 2017-05-14 11:04:58.882 plugin-container[2733:46439] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 11:04:58 INFO - PID 2731 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:04:58 INFO - PID 2731 | 2017-05-14 11:04:58.882 plugin-container[2733:46439] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:04:59 INFO - TEST-INFO | 2731: exit 0 11:04:59 INFO - Browser initialized. 11:04:59 INFO - Running cycle 1/1 for kraken test... 11:04:59 INFO - TEST-INFO | started process 2734 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmph4pt1K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:05:01 INFO - PID 2734 | 2017-05-14 11:05:01.468 plugin-container[2735:46534] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 11:05:01 INFO - PID 2734 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:01 INFO - PID 2734 | 2017-05-14 11:05:01.469 plugin-container[2735:46534] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 11:05:01 INFO - PID 2734 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:01 INFO - PID 2734 | 2017-05-14 11:05:01.469 plugin-container[2735:46534] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:05:02 INFO - PID 2734 | 2017-05-14 11:05:02.053 plugin-container[2736:46584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 11:05:02 INFO - PID 2734 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:02 INFO - PID 2734 | 2017-05-14 11:05:02.054 plugin-container[2736:46584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 11:05:02 INFO - PID 2734 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:05:02 INFO - PID 2734 | 2017-05-14 11:05:02.054 plugin-container[2736:46584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:05:11 INFO - PID 2734 | RSS: Main: 235569152 11:05:11 INFO - PID 2734 | 11:05:14 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60703/tests/kraken/driver.html?testName=audio-beat-detection) 11:05:15 INFO - PID 2734 | RSS: Main: 247939072 11:05:15 INFO - PID 2734 | 11:05:17 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60703/tests/kraken/driver.html?testName=audio-dft) 11:05:18 INFO - PID 2734 | RSS: Main: 256184320 11:05:18 INFO - PID 2734 | 11:05:21 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60703/tests/kraken/driver.html?testName=audio-fft) 11:05:22 INFO - PID 2734 | RSS: Main: 258260992 11:05:22 INFO - PID 2734 | 11:05:24 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60703/tests/kraken/driver.html?testName=audio-oscillator) 11:05:25 INFO - PID 2734 | RSS: Main: 260091904 11:05:25 INFO - PID 2734 | 11:05:26 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60703/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:05:27 INFO - PID 2734 | RSS: Main: 260476928 11:05:27 INFO - PID 2734 | 11:05:30 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60703/tests/kraken/driver.html?testName=imaging-darkroom) 11:05:31 INFO - PID 2734 | RSS: Main: 265916416 11:05:31 INFO - PID 2734 | 11:05:34 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60703/tests/kraken/driver.html?testName=imaging-desaturate) 11:05:34 INFO - PID 2734 | RSS: Main: 266256384 11:05:34 INFO - PID 2734 | 11:05:37 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60703/tests/kraken/driver.html?testName=json-parse-financial) 11:05:38 INFO - PID 2734 | RSS: Main: 265924608 11:05:38 INFO - PID 2734 | 11:05:39 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60703/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:05:40 INFO - PID 2734 | RSS: Main: 265502720 11:05:40 INFO - PID 2734 | 11:05:42 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:05:42 INFO - PID 2734 | RSS: Main: 265773056 11:05:42 INFO - PID 2734 | 11:05:44 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:05:45 INFO - PID 2734 | RSS: Main: 265175040 11:05:45 INFO - PID 2734 | 11:05:48 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:05:49 INFO - PID 2734 | RSS: Main: 265240576 11:05:49 INFO - PID 2734 | 11:05:52 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:05:53 INFO - PID 2734 | RSS: Main: 265560064 11:05:53 INFO - PID 2734 | 11:05:54 INFO - PID 2734 | Cycle 1(1): loaded http://localhost:60703/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60703/tests/kraken/driver.html?testName=ai-astar) 11:05:55 INFO - PID 2734 | RSS: Main: 266985472 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | __start_tp_report 11:05:55 INFO - PID 2734 | _x_x_mozilla_page_load 11:05:55 INFO - PID 2734 | _x_x_mozilla_page_load_details 11:05:55 INFO - PID 2734 | |i|pagename|runs| 11:05:55 INFO - PID 2734 | |0;ai-astar;102;115;113;113;108;114;95;133;171;169 11:05:55 INFO - PID 2734 | |1;audio-beat-detection;145;110;112;150;112;114;126;142;165;111 11:05:55 INFO - PID 2734 | |2;audio-dft;210;196;203;257;262;275;279;198;194;200 11:05:55 INFO - PID 2734 | |3;audio-fft;81;57;60;86;61;57;57;59;77;57 11:05:55 INFO - PID 2734 | |4;audio-oscillator;90;69;84;72;70;70;85;68;84;114 11:05:55 INFO - PID 2734 | |5;imaging-gaussian-blur;90;91;90;90;90;93;90;86;137;97 11:05:55 INFO - PID 2734 | |6;imaging-darkroom;103;95;97;99;98;96;96;97;98;96 11:05:55 INFO - PID 2734 | |7;imaging-desaturate;125;137;94;92;90;89;89;89;90;94 11:05:55 INFO - PID 2734 | |8;json-parse-financial;48;51;48;49;51;49;52;51;52;51 11:05:55 INFO - PID 2734 | |9;json-stringify-tinderbox;56;61;58;58;73;83;49;64;82;84 11:05:55 INFO - PID 2734 | |10;stanford-crypto-aes;82;83;81;84;84;84;85;86;84;82 11:05:55 INFO - PID 2734 | |11;stanford-crypto-ccm;139;139;133;134;134;141;188;171;185;139 11:05:55 INFO - PID 2734 | |12;stanford-crypto-pbkdf2;243;182;187;188;184;215;209;181;163;211 11:05:55 INFO - PID 2734 | |13;stanford-crypto-sha256-iterative;67;70;70;75;98;101;70;70;72;68 11:05:55 INFO - PID 2734 | __end_tp_report 11:05:55 INFO - PID 2734 | __start_cc_report 11:05:55 INFO - PID 2734 | _x_x_mozilla_cycle_collect,929 11:05:55 INFO - PID 2734 | __end_cc_report 11:05:55 INFO - PID 2734 | __startTimestamp1494785155071__endTimestamp 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | ------- Summary: start ------- 11:05:55 INFO - PID 2734 | Number of tests: 14 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#0] ai-astar Cycles:10 Average:123.30 Median:124.00 stddev:26.49 (21.4%) stddev-sans-first:26.95 11:05:55 INFO - PID 2734 | Values: 102.0 115.0 113.0 113.0 108.0 114.0 95.0 133.0 171.0 169.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:134.00 stddev:20.16 (15.0%) stddev-sans-first:20.50 11:05:55 INFO - PID 2734 | Values: 145.0 110.0 112.0 150.0 112.0 114.0 126.0 142.0 165.0 111.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#2] audio-dft Cycles:10 Average:227.40 Median:233.50 stddev:35.93 (15.4%) stddev-sans-first:37.55 11:05:55 INFO - PID 2734 | Values: 210.0 196.0 203.0 257.0 262.0 275.0 279.0 198.0 194.0 200.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#3] audio-fft Cycles:10 Average:65.20 Median:60.50 stddev:11.42 (18.9%) stddev-sans-first:10.58 11:05:55 INFO - PID 2734 | Values: 81.0 57.0 60.0 86.0 61.0 57.0 57.0 59.0 77.0 57.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:78.00 stddev:14.29 (18.3%) stddev-sans-first:14.75 11:05:55 INFO - PID 2734 | Values: 90.0 69.0 84.0 72.0 70.0 70.0 85.0 68.0 84.0 114.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:90.00 stddev:14.88 (16.5%) stddev-sans-first:15.65 11:05:55 INFO - PID 2734 | Values: 90.0 91.0 90.0 90.0 90.0 93.0 90.0 86.0 137.0 97.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:97.00 stddev:2.27 (2.3%) stddev-sans-first:1.27 11:05:55 INFO - PID 2734 | Values: 103.0 95.0 97.0 99.0 98.0 96.0 96.0 97.0 98.0 96.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#7] imaging-desaturate Cycles:10 Average:98.90 Median:90.00 stddev:17.26 (19.2%) stddev-sans-first:15.51 11:05:55 INFO - PID 2734 | Values: 125.0 137.0 94.0 92.0 90.0 89.0 89.0 89.0 90.0 94.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:51.00 stddev:1.55 (3.0%) stddev-sans-first:1.42 11:05:55 INFO - PID 2734 | Values: 48.0 51.0 48.0 49.0 51.0 49.0 52.0 51.0 52.0 51.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:68.50 stddev:12.73 (18.6%) stddev-sans-first:12.88 11:05:55 INFO - PID 2734 | Values: 56.0 61.0 58.0 58.0 73.0 83.0 49.0 64.0 82.0 84.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.51 (1.8%) stddev-sans-first:1.50 11:05:55 INFO - PID 2734 | Values: 82.0 83.0 81.0 84.0 84.0 84.0 85.0 86.0 84.0 82.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:140.00 stddev:22.00 (15.7%) stddev-sans-first:22.95 11:05:55 INFO - PID 2734 | Values: 139.0 139.0 133.0 134.0 134.0 141.0 188.0 171.0 185.0 139.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.30 Median:198.50 stddev:23.00 (11.6%) stddev-sans-first:17.10 11:05:55 INFO - PID 2734 | Values: 243.0 182.0 187.0 188.0 184.0 215.0 209.0 181.0 163.0 211.0 11:05:55 INFO - PID 2734 | 11:05:55 INFO - PID 2734 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:70.00 stddev:12.54 (17.9%) stddev-sans-first:12.86 11:05:55 INFO - PID 2734 | Values: 67.0 70.0 70.0 75.0 98.0 101.0 70.0 70.0 72.0 68.0 11:05:55 INFO - PID 2734 | -------- Summary: end -------- 11:05:55 INFO - PID 2734 | 11:05:55 INFO - TEST-INFO | 2734: exit 0 11:05:55 INFO - TEST-OK | kraken | took 60142ms 11:05:55 INFO - SUITE-END | took 1174s 11:05:56 INFO - Completed test suite (00:19:35) 11:05:56 INFO - javascript benchmark 11:05:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7900.654984712953, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3524.436090225566, 3555.1289933966177, 3577.2130131371105, 3581.3911635022046, 3586.3903136466797, 5926.555508336875, 6124.724387402566, 6234.127222188898, 6234.283057448395, 6258.874822503547, 5397.839464515128, 5928.347881733006, 6161.045038019114, 6180.84547886302, 6194.442500174983, 5386.138217885139, 5509.807156749514, 5536.972315138419, 5570.498655121039, 5669.716514174295, 4676.69601475903, 5481.424450432707, 5534.584906132036, 5562.054450743383, 5634.661920284776, 4914.631402644816, 5549.528290095326, 5590.329160500725, 5633.549316054727, 5647.068233741448, 4526.411566496367, 5400.729963501848, 5470.288862447856, 5534.363548191929, 5563.554915606742, 5501.9174712379345, 5517.420670829545, 5530.198121272433, 5530.806421775259, 5592.0, 6038.27540695115, 6254.21822272216, 6273.592216505913, 6279.995200767855, 6289.622622642611, 6430.099786029959, 6493.902591461132, 6607.636579988099, 6737.3936345728625, 6758.628275444898, 6795.1845778506895, 6799.558028728117, 6808.863822723518, 6820.522563420563, 6823.249442561314, 6314.93685063148, 6322.620642761449, 6329.841753956188, 6347.1114044033875, 6369.872602547923, 6661.833454163685, 6888.724451022003, 6905.1713794345, 6922.203946546211, 6950.8609827804175, 6498.447631951242, 6583.3416658334745, 6588.374104460068, 6594.637294948824, 6631.602103873783, 6732.932670673328, 6774.45804335652, 6802.8299292517095, 6809.489288303397, 6820.931790682129, 6403.711832967528, 6474.320196379406, 6513.967430162819, 6660.367265109807, 6674.399304062561, 5871.442212989759, 6621.238557565845, 6678.198616165994, 6686.598804071771, 6727.394534491919, 5336.199570064521, 5339.5728341732565, 5351.57187425005, 5354.170103633943, 5356.276902618175, 4695.43654761424, 4732.668713190113, 4747.406574178228, 4764.237721964469, 4780.11567859947, 6218.9378106219265, 6303.243610766737, 6337.081123237105, 6337.873242535124, 6353.3329000455215, 6775.322467753185, 6786.3892249697765, 6821.658917054227, 6833.726650933907, 6848.0, 6354.555181137369, 6358.300586935434, 6370.968145159244, 6376.808695739135, 6397.296297407282, 5755.740991655385, 5829.387914268941, 5848.941510584925, 5874.970625146847, 5946.375630558816, 6799.388055074968, 6828.146481689789, 6934.757283495053, 6963.059986901703, 6991.8951215730785, 6752.561083529555, 6810.114685090906, 6810.5913645181445, 6823.590584564942, 6853.109095817711, 6942.201646810479, 6949.895751563832, 6958.165020197609, 6969.337912898267, 7001.964990175016, 6465.935340646722, 6543.443807276435, 6574.868502629825, 6657.567258128206, 6699.899501507384, 6662.433693136138, 6739.898901516383, 6765.748336557753, 6807.421369183675, 6810.284920083518, 6502.187226596676, 6538.444232240313, 6591.110200123114, 6591.868162636624, 6606.0, 6338.778142764981, 6437.0, 6529.706163222571, 6575.27671956094, 6617.43751781104, 6332.113504109337, 6342.619442833445, 6465.967670161619, 6488.870222595616, 6498.0, 6360.109584658059, 6445.097686324012, 6492.35076492347, 6497.31778163305, 6596.076549283201, 5572.888542229214, 6302.149209856794, 6349.587276826992, 6395.808125756234, 6419.358064193543], "value": 6126.195571525934, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.3773825285404, 832.6710949175078, 1135.34717537416, 1153.0832987774713, 1286.1061562214254, 37260.813695931516, 38127.428088578694, 38135.427968580494, 38147.618523814715, 38223.0, 38164.23671526568, 38572.61427385725, 38630.84107476773, 38633.61366386342, 38722.4191637125, 7681.731139410122, 7857.567833769154, 8014.3187829034605, 8610.268127209163, 8662.177093176138, 8064.556449395281, 8116.391270654695, 8600.311975042014, 8691.95654021729, 8696.521691306976, 8662.956685216564, 8704.956475217647, 8706.434081784695, 8714.520701361422, 8737.519436430994, 7606.163322034572, 8491.872621910676, 8679.869801952944, 8699.347548933823, 8716.0, 8439.789005274855, 8477.915220847835, 8485.533295668736, 8529.74410767678, 8535.0, 37303.62696373029, 37343.879683609266, 37581.0, 37638.43542346867, 37694.0, 3617.855285788565, 3647.0, 3655.94516082259, 3656.3601369760186, 3659.451082337671, 30529.847350763102, 30811.537826932614, 30822.229444264074, 30834.53748193779, 30846.691533084606, 3121.6566177720433, 3140.0736782648946, 3161.8577164027674, 3168.683131686836, 3174.4444722173525, 306498.0, 307057.0, 308447.91552084417, 308499.4575027133, 309269.0, 44905.326420103724, 47499.525004750205, 47655.76172119186, 47695.28457073147, 48377.5162248373, 49110.50889491059, 49380.765480863265, 49468.257976130386, 49572.0085598286, 49587.289207525224, 14095.577132686036, 14159.575212743634, 14167.504137355143, 14170.008099433147, 14179.716405672036, 11257.268277561932, 11261.493232804543, 11271.323720576627, 11276.718082048015, 11311.208215425006, 13124.278164700841, 13124.343782810822, 13127.868721312856, 13147.013973951993, 13258.0, 5704.543636509069, 5717.6551717245875, 5760.94239057604, 5789.392113828072, 5790.247267855236, 1005.6128390569684, 1044.4673216659558, 1045.8169820281573, 1072.5870539842217, 1083.106437189322, 21365.0, 24409.0, 24649.507009860063, 24771.13301034455, 24782.132625358026, 991.6231831903831, 1125.6679279612533, 1154.1343992005995, 1161.7269941563725, 1173.6596387047832, 12566.931810796003, 12572.559960401342, 12648.114631975857, 12652.291940588088, 12682.429290681941, 305935.47032264696, 306163.0, 307510.0, 307677.0, 308278.0, 52976.0, 53148.46851531435, 53252.467475325524, 53262.733686332096, 53304.46695532995, 1540.4454396417282, 1545.250553481583, 1574.7952766140559, 1582.185174635064, 1584.382090984517, 24074.712902274256, 24182.395440113432, 24334.0, 24339.39151521226, 24380.75619243785, 37464.250714985, 37554.62445375511, 37564.060898476666, 37660.62339376571, 37762.86711398663, 266083.0, 267183.0, 269385.0, 270088.0, 270696.0, 28979.84080636751, 30569.388612228075, 30984.380312394078, 33616.32767344591, 34945.650543495256, 40254.59745402508, 42887.78556107199, 45205.32192017188, 45206.0, 45387.86530336768, 29208.26979325534, 30197.547036794906, 30737.538936915516, 30938.226544336572, 31374.52938205883, 30169.547456787728, 30224.395512090075, 30815.845920770254, 31035.53446698256, 31110.22224444407, 37133.81433092817, 37256.81371593125, 37317.44023839699, 37347.62652373441, 37391.0, 25738.613920791577, 29263.85368073231, 29613.59443664006, 30559.388812224075, 31138.37723245477, 22471.43821404478, 22558.0, 23576.410589734707, 24209.884319665347, 24710.629340560266], "value": 17504.442840862714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.87183537185786, 427.6471910673695, 429.10959758501116, 434.8977990172309, 461.1675924955456, 19342.226310947546, 21029.894850525747, 21788.782112178873, 21900.342989710294, 22039.889800551016, 21584.568308633818, 22783.772162278372, 23011.0, 23160.536789264206, 23185.768142318615, 6036.547258955585, 6169.629822210682, 6224.0663900415075, 6240.8439789005415, 6251.531135164858, 4889.902201955959, 6082.452579267865, 6142.447179753844, 6146.66193359365, 6180.81457556274, 5852.619579727326, 6050.606710563821, 6105.847353816168, 6124.142620033199, 6128.448439640431, 6002.729877155517, 6106.572539922207, 6143.139960405524, 6146.262448506163, 6157.138000679908, 5383.2194331821665, 5397.271368365299, 5449.373322067973, 5455.345358556999, 5480.945190548084, 26982.73017269822, 27171.1848644541, 27326.45347093047, 28549.714502854913, 28567.857160714273, 2244.2930476899724, 2305.296884450242, 2319.060780383947, 2327.8836058197194, 2334.5447637710654, 14709.338079786436, 17869.910650446796, 18194.727079093824, 18250.361237356763, 18526.629467410712, 2115.1962254343407, 2119.9152033918626, 2145.817605503534, 2152.13914434226, 2159.2982280758815, 140893.0, 174976.12511937358, 175050.0, 175915.0, 177466.0, 12602.747945041048, 12659.556915507914, 12761.553345632858, 12794.23234605908, 13000.414981325863, 16220.756688649679, 16655.00069995804, 16680.165991700367, 16689.66620667604, 16751.162441877856, 7600.771976840703, 8738.432001919982, 9014.59434325457, 9042.909570904338, 9172.6789562365, 8582.527960962207, 8605.440646357967, 8605.956970215235, 8806.471611703319, 8808.0, 7849.293563579306, 7911.0, 7932.365410767125, 7998.80002999918, 8016.959915200387, 2140.839437042228, 2151.4943988162768, 2154.6083846929896, 2161.2867753641262, 2317.629179331299, 939.7313626604166, 962.1965658675085, 980.1766516126488, 1007.7128018514688, 1026.7279171019686, 21051.68422473664, 21298.680519792215, 21385.89307053455, 21593.35219943404, 21788.56422871534, 990.3018372047833, 1016.7813920007234, 1034.8654674892366, 1045.482486169351, 1053.657561292583, 10401.075800976843, 10946.890531094747, 10989.767219197058, 11119.110471162288, 13254.73490530184, 550365.0, 553900.0, 560434.0, 560631.0, 563020.0, 117485.0, 128283.71716282904, 131523.55955505563, 133738.6261373855, 136376.0, 1059.2955684469896, 1240.3612139748218, 1252.348778635123, 1256.1709271880516, 1265.943032563537, 18090.819091808913, 18563.81436185675, 21644.67532987038, 21890.23384181546, 22562.210322638628, 56298.43701562932, 57434.851302974545, 57706.0, 58603.70698146652, 58873.0, 73190.63404682942, 77780.61109694415, 78316.60841695755, 79830.80253796082, 80258.0, 8459.0, 8596.640199422995, 8743.125687431206, 9196.908030919873, 9332.440053596805, 13423.731525369632, 13988.37052332668, 13990.440382384591, 14043.297835108408, 14487.275636217935, 11759.353235572125, 11963.940180299043, 11976.76046479083, 11981.28112313264, 12081.75836483283, 10332.638357657446, 10648.988346107417, 10913.0, 11312.83030754523, 11577.363245021605, 10240.692779216635, 11426.628804543509, 11961.581344652895, 12074.577389791315, 12174.269543827399, 7423.0, 7736.994190755391, 8868.15752503511, 8918.73243802687, 8993.370464067453, 11862.762744744885, 12006.939965300118, 12126.818097728365, 12127.393630318626, 12166.939165304117], "value": 10535.297373112919, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.9238645681257, 1471.5585324402678, 1475.43933305344, 1478.186997151567, 1479.9038062525935, 11763.0, 11951.521939122447, 12224.144309898289, 12324.568640097586, 12331.691707707312, 11877.76244475112, 12081.456334464949, 12082.637520874388, 12094.0, 12101.0, 2580.896764129437, 2614.215735279418, 2630.447606002742, 2630.5791073428254, 2632.4735052989477, 2138.593667203234, 2535.5816290312077, 2587.5471792436247, 2591.235585502272, 2591.4557942831934, 2559.4753075619456, 2559.820812543123, 2602.10227471522, 2605.0361366294496, 2615.0324379979434, 2533.1640558615613, 2558.6417901493714, 2576.9742302577015, 2587.650667159928, 2588.2235329401105, 2135.1993002624017, 2205.724284464442, 2513.3842208658994, 2563.1797824696105, 2580.909668161605, 11490.942545287304, 11866.644000679993, 11869.46587403569, 11871.703207419798, 11888.28670279786, 6623.3376662333385, 6861.279565645636, 6880.862382752317, 6881.174259088892, 6907.171139463247, 3983.521977362706, 4272.33778764292, 4380.970471939092, 4393.6045755881805, 4412.227860124465, 1230.138902768066, 1246.569933372985, 1264.4689230523202, 1284.1396264502805, 1286.6847622332496, 16489.752653710202, 16512.174391280387, 16602.418915338025, 16682.416115436023, 16687.499375018768, 5429.837104886859, 5500.614956952975, 5580.720963951786, 5739.426057394311, 5776.33572139201, 11763.705907352214, 11883.821742673694, 11963.64109076729, 11971.580994665144, 11971.64085077449, 6506.60960342381, 6545.934540654628, 6643.5017373697165, 6650.70071846768, 6656.600603963778, 6136.662483563447, 6186.505079593621, 6211.565190436717, 6288.213973253343, 6298.464630506458, 4268.765217912983, 4436.00189957257, 4451.109778044404, 4481.058977614673, 4516.525764794715, 1018.5162048027128, 1056.9418681972566, 1103.5309993252836, 1112.2603468693228, 1322.418136020148, 808.7775861638097, 815.5392203405057, 835.5571547080058, 844.5566077809076, 868.3574155125162, 6307.306196318401, 6331.303556608769, 6411.326810684905, 6448.613083215022, 6529.412352888168, 587.9059350503899, 603.3001718007091, 612.5161122713096, 613.871087071711, 614.8186285045922, 3336.566246387954, 3822.6559609635265, 4050.4936882889638, 4395.208862404734, 4828.285958484757, 14895.627609309637, 15361.385544578316, 15416.46042388511, 15483.922580387027, 15537.84462155364, 13824.585262441942, 14200.0, 14901.180435075958, 15365.308561114776, 15390.692186156439, 8535.0, 8610.913890861137, 8668.133186681282, 8798.736037919001, 8829.293656507463, 6949.861002779815, 7204.135503739585, 7366.373858222111, 7383.114026316876, 7407.0, 13543.984201185147, 13571.321433928066, 13720.65698357549, 13752.65618359549, 13772.380242888894, 10799.730006749895, 13837.584872453843, 13860.930695346458, 14040.78938815939, 14338.354774035402, 8311.667533298842, 8404.117567655308, 8519.148085191431, 8519.48883067018, 8520.19058189471, 10774.138068954622, 10835.891641083488, 10865.565377384815, 10933.781324373309, 11027.448627568428, 9287.72136835896, 9331.206847418045, 9354.859677104985, 9418.529073546433, 9473.0, 9139.497327647043, 9147.0, 9156.816863662556, 9201.033891441302, 9248.861267080865, 8325.167483251626, 8356.373272004503, 8498.362622803437, 8508.702195423131, 8526.701565445179, 5586.86032849182, 5981.192539007352, 6070.696465176812, 6118.724657390339, 6286.245650521967, 6194.938050619436, 6309.148264984168, 6386.840328991812, 6452.064450654722, 6530.738770449128], "value": 5370.775452630136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.4089456869012, 395.6735693053231, 401.18158955730314, 403.74564024664465, 414.78845788647783, 5711.685857277848, 5731.45551172639, 5946.3161736400325, 6299.0866324383, 6314.40013198746, 6008.909866352008, 6082.239720034996, 6138.723757430916, 6140.526273694315, 6182.876342473148, 4383.824647014116, 4682.180618391776, 4688.203005489075, 4705.270683044134, 4713.882152946169, 3710.239400922804, 3859.671927886133, 3931.7640941543455, 4426.800793964263, 4699.67102302839, 3598.6581274778987, 4318.611324980751, 4324.243257429953, 4613.723176609415, 4647.88380290492, 4062.634362907323, 4177.979110104445, 4641.28272539161, 4647.279671650899, 4704.717716936961, 3593.74765836918, 3776.206996530732, 3946.565877753445, 4135.97932010341, 4151.211269858717, 5270.631055826094, 5517.227588137664, 5966.971229781851, 5982.820515384546, 6066.877627638901, 4798.616110711135, 4938.530839570235, 5499.532539734128, 5553.500184983371, 5564.944350556483, 5406.972965135149, 6308.413550444342, 6366.949453340193, 6650.634215118166, 6660.733570657168, 4127.7936103195025, 4375.124975005012, 4394.4067550880545, 4639.976800116012, 4711.670183087185, 6859.279775623538, 6976.825579360506, 6988.336108069725, 7006.684699188549, 7088.475977664827, 4037.3338399164395, 4378.233809083461, 4389.539098394686, 4392.824287028483, 4406.700023493077, 3716.4053751399647, 4461.57615026572, 4473.597376236155, 4484.68607197493, 4628.0, 4413.3159360299205, 4594.805350608865, 5304.655197412156, 5347.973260133675, 5370.898965712097, 5569.526590239875, 6487.6756162191705, 6676.699548520328, 6705.329467053353, 6712.932870671329, 1509.456595625574, 1529.7399442094647, 1532.5862017255279, 1537.2928452911563, 1538.0, 996.8903420623726, 1000.2295936192897, 1000.7047920863363, 1005.7435353984687, 1025.876894772617, 6333.90499142504, 6360.300366959631, 6361.543033820676, 6391.201099862517, 6428.332242119562, 5567.47109024642, 5666.150077488327, 6313.463355614732, 6339.714712837934, 6943.513954023271, 4834.903301933941, 5116.386033675908, 6303.172080096812, 6386.169797926333, 6410.0, 3313.7680362374886, 3590.56913170416, 3617.4935509028755, 3623.836927338276, 3685.447182922583, 5633.352164501053, 6331.06902394774, 6566.0, 7013.614251216232, 7017.824554386181, 6205.851917395296, 6670.232923213893, 7125.465590080765, 7130.25132361105, 7155.749548765768, 5779.566532509994, 5973.492253158443, 6987.637410704965, 7139.892901606584, 7210.466871233058, 5812.309183668485, 6836.495970886397, 6908.930910690829, 6925.238223795278, 6932.792016239521, 6233.407826256498, 6255.40573645503, 6769.526133170631, 6823.215330237086, 6911.447084233148, 6192.907106393498, 6968.512204145661, 7169.426445884213, 7212.71149154028, 7233.31283528064, 4469.284914413678, 4476.888077798081, 5067.670601410897, 5079.034983353151, 5101.9744901276745, 5636.830895073154, 6808.829779255559, 6863.656817159222, 6986.685599147949, 7044.506884518035, 6738.629375384205, 6740.595564266159, 6744.393004629607, 6763.560368576027, 6768.424683901923, 5663.745131469176, 5686.488216060575, 6367.204099487564, 6412.454941330039, 6724.4284235840505, 6525.282218956006, 6542.238213027433, 6559.6064236145985, 6604.438622717123, 6621.212272686444, 5216.869578260574, 5763.636908867345, 6210.664707087998, 6549.672516374258, 6707.295733947865, 5029.547340739351, 5752.942470575241, 6303.905441418291, 6479.1901012373455, 6595.142631458071], "value": 4774.664705494732, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.9029481020062, 777.265484117509, 822.0792712162372, 853.7609469348585, 865.1219012702109, 13494.662633434169, 16333.75499367509, 16601.50195494134, 18319.0, 18927.52681182971, 17388.826111738912, 17925.91037044816, 17958.281668733267, 18398.632027359447, 18595.163217655205, 978.5107446276861, 995.7261753017906, 997.3915911294105, 1001.2790790630734, 1004.3070281505737, 959.1176117971448, 996.8884693566657, 1020.8723909511311, 1022.3508072374059, 1025.8153532364172, 1074.6722249713835, 1076.0692001418795, 1081.3403823667595, 1082.3668154129844, 1088.0751361342875, 1053.031211285619, 1056.2342301831188, 1066.221658189522, 1066.807974564578, 1076.983845242322, 765.6439755513713, 768.5119948832476, 768.7924260449647, 779.2129948751744, 785.6229010075139, 15842.683146337124, 16260.75608865868, 17271.0, 17471.475855724348, 17492.300307987665, 17332.653346933115, 17382.3047078118, 17577.945323280645, 17684.0, 17840.286388544442, 14294.428222871073, 14489.42042318306, 14584.927075364662, 14639.853601464061, 14646.780298295535, 3641.2353405784816, 3647.6717095461536, 3657.213699054716, 3687.317846198435, 3690.446433035066, 99141.0, 115350.84649153569, 122985.0, 123199.0, 125184.74815251913, 13755.518556850462, 13857.030007899551, 13899.16605003703, 13979.0, 14043.929780351033, 25439.491210175693, 26083.347916302246, 26115.347116322246, 26226.344341391236, 26276.21171364862, 7893.921060789318, 7996.0804507481225, 8004.399670024655, 8024.117347091816, 8026.7591972240925, 3893.0267433141717, 3914.5124852555846, 3917.353636649978, 3941.428492868518, 3970.3448930926074, 4354.869353919387, 4421.668374871898, 4460.933086003713, 4462.085272519126, 4468.597826195658, 928.6935311347239, 963.8746962894779, 967.086103632075, 976.028851292968, 976.9218462522981, 663.8771827211981, 668.5453891353828, 673.9258681545026, 681.6693903456819, 699.78656509765, 10678.572857085785, 11125.276857004268, 11745.481456463569, 11942.104342174373, 12026.699332516582, 643.8551325951623, 660.9768658096943, 668.0713807807152, 676.1075380497795, 698.543536725922, 4861.29511220871, 4871.952530205952, 4900.313956046157, 4956.4547899731, 5054.671446355975, 70771.0, 80515.59742201252, 86433.13566864376, 91229.54385228032, 99133.0, 89399.55300223458, 91071.54464227769, 91357.0, 91926.0, 93136.0, 56825.43174568201, 76692.0, 77750.83373749399, 83935.0, 84881.72677409844, 11024.834627480755, 11147.996680298487, 11545.653630391102, 11571.13216508782, 11582.015528680156, 41673.541426049946, 41781.16437671169, 41894.37158442565, 42120.0, 42306.7307980761, 54429.0, 54642.180367293724, 54950.0, 55291.0, 55642.443575563724, 14355.0, 16618.58453538671, 17455.56361090983, 17473.82526174722, 17480.30078796834, 17814.643707126044, 18087.0, 18138.093095345444, 18706.345277915207, 18920.243190272784, 15643.921780391025, 16626.58433539171, 16707.331706731595, 17505.912470438077, 17847.643047139245, 16361.427350042692, 17141.57146071358, 17350.13249337503, 17568.912155439142, 17587.736183957506, 16177.838221618103, 16205.351785928431, 16850.57873553122, 17186.484405467854, 17214.65570688554, 11733.237339572901, 11782.0, 11855.229410088661, 12261.203021803554, 12342.0, 10416.345986742712, 13728.753417194952, 13740.725185496034, 13750.243736594583, 13827.79258311106], "value": 8394.253901093862, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 113.0, 113.0, 108.0, 114.0, 95.0, 133.0, 171.0, 169.0], "value": 123.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 112.0, 150.0, 112.0, 114.0, 126.0, 142.0, 165.0, 111.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 196.0, 203.0, 257.0, 262.0, 275.0, 279.0, 198.0, 194.0, 200.0], "value": 227.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 60.0, 86.0, 61.0, 57.0, 57.0, 59.0, 77.0, 57.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 84.0, 72.0, 70.0, 70.0, 85.0, 68.0, 84.0, 114.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 90.0, 90.0, 93.0, 90.0, 86.0, 137.0, 97.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 95.0, 97.0, 99.0, 98.0, 96.0, 96.0, 97.0, 98.0, 96.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 137.0, 94.0, 92.0, 90.0, 89.0, 89.0, 89.0, 90.0, 94.0], "value": 98.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 51.0, 48.0, 49.0, 51.0, 49.0, 52.0, 51.0, 52.0, 51.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 61.0, 58.0, 58.0, 73.0, 83.0, 49.0, 64.0, 82.0, 84.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 81.0, 84.0, 84.0, 84.0, 85.0, 86.0, 84.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 133.0, 134.0, 134.0, 141.0, 188.0, 171.0, 185.0, 139.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [243.0, 182.0, 187.0, 188.0, 184.0, 215.0, 209.0, 181.0, 163.0, 211.0], "value": 196.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 70.0, 75.0, 98.0, 101.0, 70.0, 70.0, 72.0, 68.0], "value": 76.1, "unit": "score"}]}]} 11:05:56 INFO - Return code: 0 11:05:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:05:56 INFO - # TBPL SUCCESS # 11:05:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:05:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:05:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:05:56 INFO - 2017-05-14 11:05:56.382 screenresolution[2741:46976] starting screenresolution argv=screenresolution get 11:05:56 INFO - 2017-05-14 11:05:56.384 screenresolution[2741:46976] Display 0: 1600x1200x32@60 11:05:56 INFO - 2017-05-14 11:05:56.390 screenresolution[2742:46979] starting screenresolution argv=screenresolution list 11:05:56 INFO - Available Modes on Display 0 11:05:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:05:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:05:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:05:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:05:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:05:56 INFO - Intel Iris: 11:05:56 INFO - Chipset Model: Intel Iris 11:05:56 INFO - Type: GPU 11:05:56 INFO - Bus: Built-In 11:05:56 INFO - VRAM (Dynamic, Max): 1536 MB 11:05:56 INFO - Vendor: Intel (0x8086) 11:05:56 INFO - Device ID: 0x0a2e 11:05:56 INFO - Revision ID: 0x0009 11:05:56 INFO - Displays: 11:05:56 INFO - Display: 11:05:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:05:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:05:56 INFO - Display Serial Number: 1600x1200 60 11:05:56 INFO - Main Display: Yes 11:05:56 INFO - Mirror: Off 11:05:56 INFO - Online: Yes 11:05:56 INFO - Rotation: Supported 11:05:56 INFO - Return code: 0 11:05:56 INFO - Running post-action listener: _resource_record_post_action 11:05:56 INFO - [mozharness: 2017-05-14 18:05:56.623538Z] Finished run-tests step (success) 11:05:56 INFO - Running post-run listener: _resource_record_post_run 11:05:56 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 1741312; Write bytes: 533154816; Read time: 11; Write time: 1673 11:05:56 INFO - TinderboxPrint: CPU usage
54.2% 11:05:56 INFO - TinderboxPrint: I/O read bytes / time
1,741,312 / 11 11:05:56 INFO - TinderboxPrint: I/O write bytes / time
533,154,816 / 1,673 11:05:56 INFO - TinderboxPrint: CPU idle
2,193.4 (45.8%) 11:05:56 INFO - TinderboxPrint: CPU system
963.6 (20.1%) 11:05:56 INFO - TinderboxPrint: CPU user
1,628.6 (34.0%) 11:05:56 INFO - TinderboxPrint: Swap in / out
215,482,368 / 0 11:05:56 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205552128; Write bytes: 201111552; Read time: 15797; Write time: 447 11:05:56 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1741312; Write bytes: 315491328; Read time: 11; Write time: 1177 11:05:57 INFO - Running post-run listener: _upload_blobber_files 11:05:57 INFO - Blob upload gear active. 11:05:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:05: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 11:05: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'] 11:05: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 11:05:57 INFO - (blobuploader) - INFO - Open directory for files ... 11:05:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:05:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:05:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:58 INFO - (blobuploader) - INFO - Done attempting. 11:05:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:05:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:05:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:05:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:05:59 INFO - (blobuploader) - INFO - Done attempting. 11:05:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:05:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:05:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:06:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:06:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:06:00 INFO - (blobuploader) - INFO - Done attempting. 11:06:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:06:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:06:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:06:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:06:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:06:02 INFO - (blobuploader) - INFO - Done attempting. 11:06:02 INFO - (blobuploader) - INFO - Iteration through files over. 11:06:02 INFO - Return code: 0 11:06:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:06:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:06:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac9e2250005669fe8636e10fbdad91c26cc2b0ff4a7422b02f4d8af39f8ba26d7e0e6d822d1bdd2bd06d7ab432b7268f01fd81908fcec2e4fbd22d5c08bfefa0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28fd256cb70934b26c055c1ff9c86f2fd72231947d41d33e6fbcf7b868e8aa9b92571ba6392e94074e874e614b6bf21d1d4691f327b357261162652474d76416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e42b00637e3bbbb8d3b12862214cb0a157dfd0f5edaa962652d6a925ce4b9ebd6fa5c384291f7be03ec8ea394dbb9fe1f4fb60c71c0bd7e1e4bca6b0b933992", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b043a0cc9ae5f21bfe9d9dc154079e6e719db1c8cc38210fddb52b42f71fd6a0825cf93b36a01a5b39d884d350800029c450cef1fdcc6d0dbeae0fa296391b3"} 11:06:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:06:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:06:02 INFO - Contents: 11:06:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac9e2250005669fe8636e10fbdad91c26cc2b0ff4a7422b02f4d8af39f8ba26d7e0e6d822d1bdd2bd06d7ab432b7268f01fd81908fcec2e4fbd22d5c08bfefa0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28fd256cb70934b26c055c1ff9c86f2fd72231947d41d33e6fbcf7b868e8aa9b92571ba6392e94074e874e614b6bf21d1d4691f327b357261162652474d76416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e42b00637e3bbbb8d3b12862214cb0a157dfd0f5edaa962652d6a925ce4b9ebd6fa5c384291f7be03ec8ea394dbb9fe1f4fb60c71c0bd7e1e4bca6b0b933992", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b043a0cc9ae5f21bfe9d9dc154079e6e719db1c8cc38210fddb52b42f71fd6a0825cf93b36a01a5b39d884d350800029c450cef1fdcc6d0dbeae0fa296391b3"} 11:06:02 INFO - Running post-run listener: copy_logs_to_upload_dir 11:06:02 INFO - Copying logs to upload dir... 11:06:02 INFO - mkdir: /builds/slave/test/build/upload/logs 11:06:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.358080 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-14 11:06:02.875872) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 11:06:02.879393) ========= 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.rxynXPlolY/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.w5F87QGRsp/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/ac9e2250005669fe8636e10fbdad91c26cc2b0ff4a7422b02f4d8af39f8ba26d7e0e6d822d1bdd2bd06d7ab432b7268f01fd81908fcec2e4fbd22d5c08bfefa0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28fd256cb70934b26c055c1ff9c86f2fd72231947d41d33e6fbcf7b868e8aa9b92571ba6392e94074e874e614b6bf21d1d4691f327b357261162652474d76416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e42b00637e3bbbb8d3b12862214cb0a157dfd0f5edaa962652d6a925ce4b9ebd6fa5c384291f7be03ec8ea394dbb9fe1f4fb60c71c0bd7e1e4bca6b0b933992", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b043a0cc9ae5f21bfe9d9dc154079e6e719db1c8cc38210fddb52b42f71fd6a0825cf93b36a01a5b39d884d350800029c450cef1fdcc6d0dbeae0fa296391b3"} build_url:https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/bX8vx-rlTe266dnO5Ihn0g/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/ac9e2250005669fe8636e10fbdad91c26cc2b0ff4a7422b02f4d8af39f8ba26d7e0e6d822d1bdd2bd06d7ab432b7268f01fd81908fcec2e4fbd22d5c08bfefa0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/28fd256cb70934b26c055c1ff9c86f2fd72231947d41d33e6fbcf7b868e8aa9b92571ba6392e94074e874e614b6bf21d1d4691f327b357261162652474d76416", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e42b00637e3bbbb8d3b12862214cb0a157dfd0f5edaa962652d6a925ce4b9ebd6fa5c384291f7be03ec8ea394dbb9fe1f4fb60c71c0bd7e1e4bca6b0b933992", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b043a0cc9ae5f21bfe9d9dc154079e6e719db1c8cc38210fddb52b42f71fd6a0825cf93b36a01a5b39d884d350800029c450cef1fdcc6d0dbeae0fa296391b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 11:06:02.913561) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 11:06:02.913945) ========= 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.rxynXPlolY/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.w5F87QGRsp/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 11:06:02.962677) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 11:06:02.962983) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 11:06:02.963299) ========= ========= Total master_lag: 0.21 =========