builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0185 starttime: 1495063935.33 results: success (0) revision: 2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.326207) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.326684) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.326972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.352378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.352841) ========= 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.EWVz1PctLp/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.siZVzDuNeG/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-17 16:32:15-- 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% 14.7M=0.001s 2017-05-17 16:32:15 (14.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129460 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.507486) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.507997) ========= 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.EWVz1PctLp/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.siZVzDuNeG/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.028547 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:15.560778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 16:32:15.561333) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077 --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 2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EWVz1PctLp/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.siZVzDuNeG/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-17 16:32:15,688 truncating revision to first 12 chars 2017-05-17 16:32:15,688 Setting DEBUG logging. 2017-05-17 16:32:15,688 attempt 1/10 2017-05-17 16:32:15,688 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c783a7b6d05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 16:32:16,704 unpacking tar archive at: mozilla-central-2c783a7b6d05/testing/mozharness/ program finished with exit code 0 elapsedTime=1.286534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 16:32:16.872824) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:16.873471) ========= script_repo_revision: 2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:16.874169) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:16.874649) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 16:32:16.894716) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-17 16:32:16.895183) ========= /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.EWVz1PctLp/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.siZVzDuNeG/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:32:17 INFO - MultiFileLogger online at 20170517 16:32:17 in /builds/slave/test 16:32:17 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 16:32:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:32:17 INFO - {'append_to_log': False, 16:32:17 INFO - 'base_work_dir': '/builds/slave/test', 16:32:17 INFO - 'blob_upload_branch': 'Firefox', 16:32:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:32:17 INFO - 'branch': 'Firefox', 16:32:17 INFO - 'buildbot_json_path': 'buildprops.json', 16:32:17 INFO - 'config_files': ('talos/mac_config.py',), 16:32:17 INFO - 'default_actions': ('clobber', 16:32:17 INFO - 'read-buildbot-config', 16:32:17 INFO - 'download-and-extract', 16:32:17 INFO - 'populate-webroot', 16:32:17 INFO - 'create-virtualenv', 16:32:17 INFO - 'install', 16:32:17 INFO - 'run-tests'), 16:32:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:32:17 INFO - 'download_minidump_stackwalk': True, 16:32:17 INFO - 'download_symbols': 'ondemand', 16:32:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:32:17 INFO - 'tooltool.py': '/tools/tooltool.py', 16:32:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:32:17 INFO - '/tools/misc-python/virtualenv.py')}, 16:32:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:32:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:32:17 INFO - 'gecko_profile': False, 16:32:17 INFO - 'gecko_profile_interval': 0, 16:32:17 INFO - 'installer_path': 'installer.exe', 16:32:17 INFO - 'log_level': 'info', 16:32:17 INFO - 'log_name': 'talos', 16:32:17 INFO - 'log_to_console': True, 16:32:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:32:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:32:17 INFO - 'opt_config_files': (), 16:32:17 INFO - 'pip_index': False, 16:32:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:32:17 INFO - 'cmd': ('bash', 16:32:17 INFO - '-c', 16:32:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:32:17 INFO - 'enabled': True, 16:32:17 INFO - 'halt_on_failure': False, 16:32:17 INFO - 'name': 'check_screen_resolution'},), 16:32:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:32:17 INFO - 'cmd': ('bash', 16:32:17 INFO - '-c', 16:32:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:32:17 INFO - 'enabled': True, 16:32:17 INFO - 'halt_on_failure': False, 16:32:17 INFO - 'name': 'check_screen_resolution'},), 16:32:17 INFO - 'run_cmd_checks_enabled': True, 16:32:17 INFO - 'suite': 'dromaeojs-e10s', 16:32:17 INFO - 'system_bits': '32', 16:32:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:32:17 INFO - 'title': 't-yosemite-r7-0185', 16:32:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:32:17 INFO - 'use_talos_json': True, 16:32:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:32:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:32:17 INFO - 'work_dir': 'build'} 16:32:17 INFO - [mozharness: 2017-05-17 23:32:17.135504Z] Running clobber step. 16:32:17 INFO - Running pre-action listener: _resource_record_pre_action 16:32:17 INFO - Running main action method: clobber 16:32:17 INFO - rmtree: /builds/slave/test/build 16:32:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:32:19 INFO - Running post-action listener: _resource_record_post_action 16:32:19 INFO - [mozharness: 2017-05-17 23:32:19.768872Z] Finished clobber step (success) 16:32:19 INFO - [mozharness: 2017-05-17 23:32:19.769004Z] Running read-buildbot-config step. 16:32:19 INFO - Running pre-action listener: _resource_record_pre_action 16:32:19 INFO - Running main action method: read_buildbot_config 16:32:19 INFO - Using buildbot properties: 16:32:19 INFO - { 16:32:19 INFO - "project": "", 16:32:19 INFO - "product": "firefox", 16:32:19 INFO - "who": "kwierso@gmail.com", 16:32:19 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 16:32:19 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:32:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:32:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:32:19 INFO - "stage_platform": "macosx64", 16:32:19 INFO - "basedir": "/builds/slave/test", 16:32:19 INFO - "buildnumber": 69, 16:32:19 INFO - "platform": "yosemite_r7", 16:32:19 INFO - "parent_task_id": "P8rk4hefQGmh-4MNfUFPNw", 16:32:19 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:32:19 INFO - "slavebuilddir": "test", 16:32:19 INFO - "taskId": "ZSo7kDUMQCCFLsTPM0xq6g", 16:32:19 INFO - "branch": "mozilla-central", 16:32:19 INFO - "script_repo_revision": "production", 16:32:19 INFO - "revision": "2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077", 16:32:19 INFO - "slavename": "t-yosemite-r7-0185", 16:32:19 INFO - "repo_path": "mozilla-central" 16:32:19 INFO - } 16:32:19 INFO - Finding installer, test and symbols from parent task. 16:32:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZSo7kDUMQCCFLsTPM0xq6g'}, attempt #1 16:32:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZSo7kDUMQCCFLsTPM0xq6g'}, attempt #1 16:32:21 INFO - Task dependencies: GxOb9-Z2TuuNS5RmI8nwSQ 16:32:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw'}, attempt #1 16:32:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:32:21 INFO - Set test_url: https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/test_packages.json 16:32:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/target.crashreporter-symbols.zip 16:32:21 INFO - Running post-action listener: _resource_record_post_action 16:32:21 INFO - [mozharness: 2017-05-17 23:32:21.454405Z] Finished read-buildbot-config step (success) 16:32:21 INFO - [mozharness: 2017-05-17 23:32:21.454530Z] Running download-and-extract step. 16:32:21 INFO - Running pre-action listener: _resource_record_pre_action 16:32:21 INFO - Running main action method: download_and_extract 16:32:21 INFO - mkdir: /builds/slave/test/build/tests 16:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:21 INFO - trying https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:32:21 INFO - Downloading https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/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 16:32:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/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 16:32:22 INFO - Downloaded 1325 bytes. 16:32:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:32:22 INFO - Using the following test package requirements: 16:32:22 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:32:22 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:32:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:32:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:32:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'jsshell-mac.zip'], 16:32:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:32:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:32:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:32:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:32:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:32:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:32:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:32:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:32:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:32:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:32:22 INFO - Fetch https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:32:24 INFO - Content-Length response header: 56567962 16:32:24 INFO - Bytes received: 56567962 16:32:41 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 16:32:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:32:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:32:41 INFO - Fetch https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:32:44 INFO - Content-Length response header: 56567962 16:32:44 INFO - Bytes received: 56567962 16:33:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:33:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:33:01 INFO - Fetch https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:33:02 INFO - Content-Length response header: 14410001 16:33:02 INFO - Bytes received: 14410001 16:33:02 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')]} 16:33:02 INFO - trying https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:33:02 INFO - Downloading https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:33:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/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 16:33:04 INFO - Downloaded 75909821 bytes. 16:33:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:33:04 INFO - mkdir: /builds/slave/test/properties 16:33:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:33:04 INFO - Writing to file /builds/slave/test/properties/build_url 16:33:04 INFO - Contents: 16:33:04 INFO - build_url:https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:33:04 INFO - Running post-action listener: _resource_record_post_action 16:33:04 INFO - Running post-action listener: set_extra_try_arguments 16:33:04 INFO - [mozharness: 2017-05-17 23:33:04.540376Z] Finished download-and-extract step (success) 16:33:04 INFO - [mozharness: 2017-05-17 23:33:04.540545Z] Running populate-webroot step. 16:33:04 INFO - Running pre-action listener: _resource_record_pre_action 16:33:04 INFO - Running main action method: populate_webroot 16:33:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:33:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:33:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:33:04 INFO - '--tppagecycles', 16:33:04 INFO - '7'], 16:33:04 INFO - 'tests': ['tsvgm']}}, 16:33:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:33:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:33:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:33:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:33:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:33:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:33:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['damp', 'tps']}, 16:33:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'tests': ['damp', 'tps']}, 16:33:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['dromaeo_dom']}, 16:33:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:33:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:33:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:33:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'tests': ['ts_paint', 'tp5o']}, 16:33:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['a11yr', 16:33:04 INFO - 'ts_paint', 16:33:04 INFO - 'tpaint', 16:33:04 INFO - 'sessionrestore', 16:33:04 INFO - 'sessionrestore_no_auto_restore', 16:33:04 INFO - 'tabpaint']}, 16:33:04 INFO - 'other-e10s': {'tests': ['a11yr', 16:33:04 INFO - 'ts_paint', 16:33:04 INFO - 'tpaint', 16:33:04 INFO - 'sessionrestore', 16:33:04 INFO - 'sessionrestore_no_auto_restore', 16:33:04 INFO - 'tabpaint']}, 16:33:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:33:04 INFO - 'ts_paint', 16:33:04 INFO - 'tpaint', 16:33:04 INFO - 'sessionrestore', 16:33:04 INFO - 'sessionrestore_no_auto_restore', 16:33:04 INFO - 'tabpaint']}, 16:33:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:33:04 INFO - 'ts_paint', 16:33:04 INFO - 'tpaint', 16:33:04 INFO - 'sessionrestore', 16:33:04 INFO - 'sessionrestore_no_auto_restore', 16:33:04 INFO - 'tabpaint']}, 16:33:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['a11yr', 16:33:04 INFO - 'ts_paint', 16:33:04 INFO - 'tpaint', 16:33:04 INFO - 'sessionrestore', 16:33:04 INFO - 'sessionrestore_no_auto_restore', 16:33:04 INFO - 'tabpaint']}, 16:33:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['a11yr', 16:33:04 INFO - 'ts_paint', 16:33:04 INFO - 'tpaint', 16:33:04 INFO - 'sessionrestore', 16:33:04 INFO - 'sessionrestore_no_auto_restore', 16:33:04 INFO - 'tabpaint']}, 16:33:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:33:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:33:04 INFO - 'bloom_basic_ref']}, 16:33:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['tsvgx', 16:33:04 INFO - 'tsvgr_opacity', 16:33:04 INFO - 'tart', 16:33:04 INFO - 'tscrollx', 16:33:04 INFO - 'cart', 16:33:04 INFO - 'tsvg_static']}, 16:33:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:33:04 INFO - 'tsvgr_opacity', 16:33:04 INFO - 'tart', 16:33:04 INFO - 'tscrollx', 16:33:04 INFO - 'cart', 16:33:04 INFO - 'tsvg_static']}, 16:33:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'talos_options': ['--disable-e10s'], 16:33:04 INFO - 'tests': ['tp5o']}, 16:33:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:33:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'talos_options': ['--disable-e10s', 16:33:04 INFO - '--xperf_path', 16:33:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:33:04 INFO - 'tests': ['tp5n']}, 16:33:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:33:04 INFO - 'talos_options': ['--xperf_path', 16:33:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:33:04 INFO - 'tests': ['tp5n']}}, 16:33:04 INFO - 'talos.zip': {'path': '', 16:33:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:33:04 INFO - Running post-action listener: _resource_record_post_action 16:33:04 INFO - [mozharness: 2017-05-17 23:33:04.562180Z] Finished populate-webroot step (success) 16:33:04 INFO - [mozharness: 2017-05-17 23:33:04.562339Z] Running create-virtualenv step. 16:33:04 INFO - Running pre-action listener: _resource_record_pre_action 16:33:04 INFO - Running main action method: create_virtualenv 16:33:04 INFO - Creating virtualenv /builds/slave/test/build/venv 16:33:04 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 16:33:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:33:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:33:04 INFO - Using real prefix '/tools/python27' 16:33:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:33:05 INFO - Installing distribute.............................................................................................................................................................................................done. 16:33:08 INFO - Installing pip.................done. 16:33:08 INFO - Return code: 0 16:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:33:08 INFO - Reading from file tmpfile_stdout 16:33:08 INFO - Output received: 16:33:08 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) 16:33:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:33:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:08 INFO - 'HOME': '/Users/cltbld', 16:33:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:08 INFO - 'LOGNAME': 'cltbld', 16:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:08 INFO - 'MOZ_NO_REMOTE': '1', 16:33:08 INFO - 'NO_EM_RESTART': '1', 16:33:08 INFO - 'PAGER': '/bin/cat', 16:33:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:08 INFO - 'PWD': '/builds/slave/test', 16:33:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:08 INFO - 'SHELL': '/bin/bash', 16:33:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:08 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:08 INFO - 'USER': 'cltbld', 16:33:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:08 INFO - 'XPC_FLAGS': '0x0', 16:33:08 INFO - 'XPC_SERVICE_NAME': '0', 16:33:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:08 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 16:33:08 INFO - Cleaning up... 16:33:08 INFO - Return code: 0 16:33:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:33:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:08 INFO - 'HOME': '/Users/cltbld', 16:33:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:08 INFO - 'LOGNAME': 'cltbld', 16:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:08 INFO - 'MOZ_NO_REMOTE': '1', 16:33:08 INFO - 'NO_EM_RESTART': '1', 16:33:08 INFO - 'PAGER': '/bin/cat', 16:33:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:08 INFO - 'PWD': '/builds/slave/test', 16:33:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:08 INFO - 'SHELL': '/bin/bash', 16:33:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:08 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:08 INFO - 'USER': 'cltbld', 16:33:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:08 INFO - 'XPC_FLAGS': '0x0', 16:33:08 INFO - 'XPC_SERVICE_NAME': '0', 16:33:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:09 INFO - Downloading/unpacking psutil>=3.1.1 16:33:09 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 16:33:09 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 16:33:09 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 16:33:09 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 16:33:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:33:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:33:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:33:11 INFO - Installing collected packages: psutil 16:33:11 INFO - Running setup.py install for psutil 16:33:11 INFO - building 'psutil._psutil_osx' extension 16:33:11 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 16:33:11 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 16:33:11 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 16:33:12 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 16:33:12 INFO - building 'psutil._psutil_posix' extension 16:33:12 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 16:33:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:33:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:33:12 INFO - ^ 16:33:12 INFO - 1 warning generated. 16:33:12 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 16:33:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:33:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:33:12 INFO - Successfully installed psutil 16:33:12 INFO - Cleaning up... 16:33:12 INFO - Return code: 0 16:33:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:33:12 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')]} 16:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:12 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')]} 16:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:13 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:13 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 16:33:13 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 16:33:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:13 INFO - 'HOME': '/Users/cltbld', 16:33:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:13 INFO - 'LOGNAME': 'cltbld', 16:33:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:13 INFO - 'MOZ_NO_REMOTE': '1', 16:33:13 INFO - 'NO_EM_RESTART': '1', 16:33:13 INFO - 'PAGER': '/bin/cat', 16:33:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:13 INFO - 'PWD': '/builds/slave/test', 16:33:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:13 INFO - 'SHELL': '/bin/bash', 16:33:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:13 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:13 INFO - 'USER': 'cltbld', 16:33:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:13 INFO - 'XPC_FLAGS': '0x0', 16:33:13 INFO - 'XPC_SERVICE_NAME': '0', 16:33:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:33:13 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 16:33:13 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 16:33:13 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 16:33:13 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 16:33:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:33:15 INFO - Installing collected packages: mozsystemmonitor 16:33:15 INFO - Running setup.py install for mozsystemmonitor 16:33:15 INFO - Successfully installed mozsystemmonitor 16:33:15 INFO - Cleaning up... 16:33:15 INFO - Return code: 0 16:33:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:33:15 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')]} 16:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:15 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')]} 16:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:15 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:15 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 16:33:15 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 16:33:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:15 INFO - 'HOME': '/Users/cltbld', 16:33:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:15 INFO - 'LOGNAME': 'cltbld', 16:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:15 INFO - 'MOZ_NO_REMOTE': '1', 16:33:15 INFO - 'NO_EM_RESTART': '1', 16:33:15 INFO - 'PAGER': '/bin/cat', 16:33:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:15 INFO - 'PWD': '/builds/slave/test', 16:33:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:15 INFO - 'SHELL': '/bin/bash', 16:33:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:15 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:15 INFO - 'USER': 'cltbld', 16:33:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:15 INFO - 'XPC_FLAGS': '0x0', 16:33:15 INFO - 'XPC_SERVICE_NAME': '0', 16:33:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:33:15 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 16:33:15 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 16:33:15 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 16:33:15 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 16:33:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:33:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:33:17 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 16:33:17 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 16:33:17 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 16:33:17 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 16:33:17 INFO - Downloading functools32-3.2.3-2.tar.gz 16:33:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:33:17 INFO - warning: no files found matching '*.txt' 16:33:17 INFO - no previously-included directories found matching 'build' 16:33:17 INFO - no previously-included directories found matching 'dist' 16:33:17 INFO - no previously-included directories found matching '.git*' 16:33:17 INFO - Installing collected packages: jsonschema, functools32 16:33:17 INFO - Running setup.py install for functools32 16:33:17 INFO - warning: no files found matching '*.txt' 16:33:17 INFO - no previously-included directories found matching 'build' 16:33:17 INFO - no previously-included directories found matching 'dist' 16:33:17 INFO - no previously-included directories found matching '.git*' 16:33:17 INFO - Successfully installed jsonschema functools32 16:33:17 INFO - Cleaning up... 16:33:17 INFO - Return code: 0 16:33:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:33:17 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')]} 16:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:17 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')]} 16:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:17 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:17 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 16:33:17 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 16:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:17 INFO - 'HOME': '/Users/cltbld', 16:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:17 INFO - 'LOGNAME': 'cltbld', 16:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:17 INFO - 'MOZ_NO_REMOTE': '1', 16:33:17 INFO - 'NO_EM_RESTART': '1', 16:33:17 INFO - 'PAGER': '/bin/cat', 16:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:17 INFO - 'PWD': '/builds/slave/test', 16:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:17 INFO - 'SHELL': '/bin/bash', 16:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:17 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:17 INFO - 'USER': 'cltbld', 16:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:17 INFO - 'XPC_FLAGS': '0x0', 16:33:17 INFO - 'XPC_SERVICE_NAME': '0', 16:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:33:17 INFO - Cleaning up... 16:33:17 INFO - Return code: 0 16:33:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:33:17 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')]} 16:33:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:17 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')]} 16:33:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:17 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:17 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 16:33:17 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 16:33:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:17 INFO - 'HOME': '/Users/cltbld', 16:33:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:17 INFO - 'LOGNAME': 'cltbld', 16:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:17 INFO - 'MOZ_NO_REMOTE': '1', 16:33:17 INFO - 'NO_EM_RESTART': '1', 16:33:17 INFO - 'PAGER': '/bin/cat', 16:33:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:17 INFO - 'PWD': '/builds/slave/test', 16:33:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:17 INFO - 'SHELL': '/bin/bash', 16:33:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:17 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:17 INFO - 'USER': 'cltbld', 16:33:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:17 INFO - 'XPC_FLAGS': '0x0', 16:33:17 INFO - 'XPC_SERVICE_NAME': '0', 16:33:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:33:18 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 16:33:18 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 16:33:18 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 16:33:18 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 16:33:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:33:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:33:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:33:19 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 16:33:19 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 16:33:19 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 16:33:19 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 16:33:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:33:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:33:20 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 16:33:20 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 16:33:20 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 16:33:20 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 16:33:20 INFO - Downloading docopt-0.6.1.tar.gz 16:33:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:33:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:33:20 INFO - Running setup.py install for blobuploader 16:33:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:33:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:33:20 INFO - Running setup.py install for requests 16:33:21 INFO - Running setup.py install for docopt 16:33:21 INFO - Successfully installed blobuploader requests docopt 16:33:21 INFO - Cleaning up... 16:33:21 INFO - Return code: 0 16:33:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:21 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:21 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 16:33:21 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 16:33:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:21 INFO - 'HOME': '/Users/cltbld', 16:33:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:21 INFO - 'LOGNAME': 'cltbld', 16:33:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:21 INFO - 'MOZ_NO_REMOTE': '1', 16:33:21 INFO - 'NO_EM_RESTART': '1', 16:33:21 INFO - 'PAGER': '/bin/cat', 16:33:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:21 INFO - 'PWD': '/builds/slave/test', 16:33:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:21 INFO - 'SHELL': '/bin/bash', 16:33:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:21 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:21 INFO - 'USER': 'cltbld', 16:33:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:21 INFO - 'XPC_FLAGS': '0x0', 16:33:21 INFO - 'XPC_SERVICE_NAME': '0', 16:33:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:33:21 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-gLLU0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:33:21 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-iGNj4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:33:21 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-FkFycz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:33:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:33:21 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-v4GPoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-FHgDfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-LgcojG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-_VZC43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-UFr6TK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-88auPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-z63CbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-dRSZF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-GpcdWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-J0neTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:33:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:33:22 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-YRqkgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:23 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-_Lk0z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:33:23 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-wtwoEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:33:23 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-aiyzXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:33:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:33:23 INFO - Running setup.py install for manifestparser 16:33:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:33:23 INFO - Running setup.py install for mozcrash 16:33:23 INFO - Running setup.py install for mozdebug 16:33:23 INFO - Running setup.py install for mozdevice 16:33:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:33:23 INFO - Running setup.py install for mozfile 16:33:24 INFO - Running setup.py install for mozhttpd 16:33:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Running setup.py install for mozinfo 16:33:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Running setup.py install for mozInstall 16:33:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Running setup.py install for mozleak 16:33:24 INFO - Running setup.py install for mozlog 16:33:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:33:24 INFO - Running setup.py install for moznetwork 16:33:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:33:25 INFO - Running setup.py install for mozprocess 16:33:25 INFO - Running setup.py install for mozprofile 16:33:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:33:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:33:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:33:25 INFO - Running setup.py install for mozrunner 16:33:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:33:25 INFO - Running setup.py install for mozscreenshot 16:33:25 INFO - Running setup.py install for moztest 16:33:25 INFO - Running setup.py install for mozversion 16:33:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:33:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:33:25 INFO - Cleaning up... 16:33:25 INFO - Return code: 0 16:33:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:33:25 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')]} 16:33:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:25 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')]} 16:33:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:25 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:25 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 16:33:25 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 16:33:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:25 INFO - 'HOME': '/Users/cltbld', 16:33:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:25 INFO - 'LOGNAME': 'cltbld', 16:33:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:25 INFO - 'MOZ_NO_REMOTE': '1', 16:33:25 INFO - 'NO_EM_RESTART': '1', 16:33:25 INFO - 'PAGER': '/bin/cat', 16:33:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:25 INFO - 'PWD': '/builds/slave/test', 16:33:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:25 INFO - 'SHELL': '/bin/bash', 16:33:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:25 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:25 INFO - 'USER': 'cltbld', 16:33:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:25 INFO - 'XPC_FLAGS': '0x0', 16:33:25 INFO - 'XPC_SERVICE_NAME': '0', 16:33:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-rnUQzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-oUQDWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-CJyH49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-DWF8FR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-6aQaKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-aM20gN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-sVhjmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:33:26 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)) 16:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:33:26 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-tZIh9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-VxH48K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-8jwlNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-8cwbh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-0WS5dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-B4U9H5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-EH8Ecm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-pt8Vg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-FMzgl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:33:27 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)) 16:33:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:33:27 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-5o5Fan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:33:28 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)) 16:33:28 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)) 16:33:28 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)) 16:33:28 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)) 16:33:28 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)) 16:33:28 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)) 16:33:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:33:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:33:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:33:29 INFO - Downloading blessings-1.6.tar.gz 16:33:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:33:29 INFO - Installing collected packages: blessings 16:33:29 INFO - Running setup.py install for blessings 16:33:29 INFO - Successfully installed blessings 16:33:29 INFO - Cleaning up... 16:33:29 INFO - Return code: 0 16:33:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:33:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:33:29 INFO - Reading from file tmpfile_stdout 16:33:29 INFO - Current package versions: 16:33:29 INFO - blessings == 1.6 16:33:29 INFO - blobuploader == 1.2.4 16:33:29 INFO - docopt == 0.6.1 16:33:29 INFO - functools32 == 3.2.3-2 16:33:29 INFO - jsonschema == 2.5.1 16:33:29 INFO - manifestparser == 1.1 16:33:29 INFO - mozInstall == 1.12 16:33:29 INFO - mozcrash == 1.0 16:33:29 INFO - mozdebug == 0.1 16:33:29 INFO - mozdevice == 0.50 16:33:29 INFO - mozfile == 1.2 16:33:29 INFO - mozhttpd == 0.7 16:33:29 INFO - mozinfo == 0.9 16:33:29 INFO - mozleak == 0.1 16:33:29 INFO - mozlog == 3.4 16:33:29 INFO - moznetwork == 0.27 16:33:29 INFO - mozprocess == 0.25 16:33:29 INFO - mozprofile == 0.28 16:33:29 INFO - mozrunner == 6.13 16:33:29 INFO - mozscreenshot == 0.1 16:33:29 INFO - mozsystemmonitor == 0.3 16:33:29 INFO - moztest == 0.8 16:33:29 INFO - mozversion == 1.4 16:33:29 INFO - psutil == 3.1.1 16:33:29 INFO - requests == 1.2.3 16:33:29 INFO - wsgiref == 0.1.2 16:33:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:33:29 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')]} 16:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:29 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')]} 16:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:29 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:29 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 16:33:29 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 16:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:29 INFO - 'HOME': '/Users/cltbld', 16:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:29 INFO - 'LOGNAME': 'cltbld', 16:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:29 INFO - 'MOZ_NO_REMOTE': '1', 16:33:29 INFO - 'NO_EM_RESTART': '1', 16:33:29 INFO - 'PAGER': '/bin/cat', 16:33:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:29 INFO - 'PWD': '/builds/slave/test', 16:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:29 INFO - 'SHELL': '/bin/bash', 16:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:29 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:29 INFO - 'USER': 'cltbld', 16:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:30 INFO - 'XPC_FLAGS': '0x0', 16:33:30 INFO - 'XPC_SERVICE_NAME': '0', 16:33:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 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)) 16:33:30 INFO - Cleaning up... 16:33:30 INFO - Return code: 0 16:33:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:30 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 0x104586b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc463cb90a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, '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.EWVz1PctLp/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.siZVzDuNeG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 16:33:30 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 16:33:30 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 16:33:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:30 INFO - 'HOME': '/Users/cltbld', 16:33:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:30 INFO - 'LOGNAME': 'cltbld', 16:33:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:30 INFO - 'MOZ_NO_REMOTE': '1', 16:33:30 INFO - 'NO_EM_RESTART': '1', 16:33:30 INFO - 'PAGER': '/bin/cat', 16:33:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:30 INFO - 'PWD': '/builds/slave/test', 16:33:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:30 INFO - 'SHELL': '/bin/bash', 16:33:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:30 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:30 INFO - 'USER': 'cltbld', 16:33:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:30 INFO - 'XPC_FLAGS': '0x0', 16:33:30 INFO - 'XPC_SERVICE_NAME': '0', 16:33:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:33:30 INFO - Cleaning up... 16:33:30 INFO - Return code: 0 16:33:30 INFO - Running post-action listener: _resource_record_post_action 16:33:30 INFO - Running post-action listener: _start_resource_monitoring 16:33:30 INFO - Starting resource monitoring. 16:33:30 INFO - [mozharness: 2017-05-17 23:33:30.554252Z] Finished create-virtualenv step (success) 16:33:30 INFO - [mozharness: 2017-05-17 23:33:30.555299Z] Running install step. 16:33:30 INFO - Running pre-action listener: _resource_record_pre_action 16:33:30 INFO - Running main action method: install 16:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:33:30 INFO - Reading from file tmpfile_stdout 16:33:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:33:30 INFO - Reading from file tmpfile_stdout 16:33:30 INFO - Output received: 16:33:30 INFO - Usage: mozinstall [options] installer 16:33:30 INFO - Options: 16:33:30 INFO - -h, --help show this help message and exit 16:33:30 INFO - -d DEST, --destination=DEST 16:33:30 INFO - Directory to install application into. [default: 16:33:30 INFO - "/builds/slave/test"] 16:33:30 INFO - --app=APP Application being installed. [default: firefox] 16:33:30 INFO - mkdir: /builds/slave/test/build/application 16:33:30 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'] 16:33:30 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 16:33:51 INFO - Reading from file tmpfile_stdout 16:33:51 INFO - Output received: 16:33:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:33:51 INFO - Running post-action listener: _resource_record_post_action 16:33:51 INFO - [mozharness: 2017-05-17 23:33:51.702309Z] Finished install step (success) 16:33:51 INFO - [mozharness: 2017-05-17 23:33:51.702436Z] Running run-tests step. 16:33:51 INFO - Running pre-action listener: _resource_record_pre_action 16:33:51 INFO - Running main action method: run_tests 16:33:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:33:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:33:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:33:51 INFO - 2017-05-17 16:33:51.716 screenresolution[1625:7348] starting screenresolution argv=screenresolution get 16:33:51 INFO - 2017-05-17 16:33:51.719 screenresolution[1625:7348] Display 0: 1600x1200x32@60 16:33:51 INFO - 2017-05-17 16:33:51.725 screenresolution[1626:7350] starting screenresolution argv=screenresolution list 16:33:51 INFO - Available Modes on Display 0 16:33:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:33:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:33:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:33:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:33:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:33:51 INFO - Intel Iris: 16:33:51 INFO - Chipset Model: Intel Iris 16:33:51 INFO - Type: GPU 16:33:51 INFO - Bus: Built-In 16:33:51 INFO - VRAM (Dynamic, Max): 1536 MB 16:33:51 INFO - Vendor: Intel (0x8086) 16:33:51 INFO - Device ID: 0x0a2e 16:33:51 INFO - Revision ID: 0x0009 16:33:51 INFO - Displays: 16:33:51 INFO - Display: 16:33:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:33:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:33:51 INFO - Display Serial Number: 1600x1200 60 16:33:51 INFO - Main Display: Yes 16:33:51 INFO - Mirror: Off 16:33:51 INFO - Online: Yes 16:33:51 INFO - Rotation: Supported 16:33:51 INFO - Return code: 0 16:33:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:33:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:33:51 INFO - Python 2.7.3 16:33:51 INFO - Return code: 0 16:33:51 INFO - grabbing minidump binary from tooltool 16:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:51 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 0x104296d30>, '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 0x10424adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:33:51 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 16:33:51 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 16:33:51 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 16:33:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:33:52 INFO - Return code: 0 16:33:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:33:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:33:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:33:52 INFO - ENV: RUST_BACKTRACE is now 1 16:33:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:33:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:33:52 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/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 16:33:52 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/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0185 --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 16:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EWVz1PctLp/Render', 16:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:33:52 INFO - 'HOME': '/Users/cltbld', 16:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:33:52 INFO - 'LOGNAME': 'cltbld', 16:33:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:33:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:52 INFO - 'MOZ_NO_REMOTE': '1', 16:33:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:33:52 INFO - 'NO_EM_RESTART': '1', 16:33:52 INFO - 'PAGER': '/bin/cat', 16:33:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:52 INFO - 'PWD': '/builds/slave/test', 16:33:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:33:52 INFO - 'RUST_BACKTRACE': '1', 16:33:52 INFO - 'SHELL': '/bin/bash', 16:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siZVzDuNeG/Listeners', 16:33:52 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 16:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:33:52 INFO - 'USER': 'cltbld', 16:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:52 INFO - 'XPC_FLAGS': '0x0', 16:33:52 INFO - 'XPC_SERVICE_NAME': '0', 16:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:33:52 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/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 16:33:52 INFO - mozversion application_buildid: 20170517145843 16:33:52 INFO - mozversion application_changeset: 2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077 16:33:52 INFO - mozversion application_display_name: Nightly 16:33:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:33:52 INFO - mozversion application_name: Firefox 16:33:52 INFO - mozversion application_remotingname: firefox 16:33:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:33:52 INFO - mozversion application_vendor: Mozilla 16:33:52 INFO - mozversion application_version: 55.0a1 16:33:52 INFO - mozversion platform_buildid: 20170517145843 16:33:52 INFO - mozversion platform_changeset: 2c783a7b6d05b4b2b417bc5f21b7e40cbf3df077 16:33:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:33:52 INFO - mozversion platform_version: 55.0a1 16:33:52 INFO - using testdate: 1495064032 16:33:52 INFO - actual date: 1495064032 16:33:52 INFO - starting webserver on 'localhost:49229' 16:33:52 INFO - SUITE-START | Running 2 tests 16:33:52 INFO - TEST-START | dromaeo_css 16:33:52 INFO - operating with platform_type : mac_ 16:33:52 INFO - Initialising browser for dromaeo_css test... 16:33:52 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpZxlNRP/profile http://localhost:49229/getInfo.html) 16:33:52 INFO - PID 1636 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2939: String.localeCompare is deprecated; use String.prototype.localeCompare instead 16:33:54 INFO - PID 1636 | 2017-05-17 16:33:54.682 plugin-container[1638:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c37, name = 'com.apple.tsm.portname' 16:33:54 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:33:54 INFO - PID 1636 | 2017-05-17 16:33:54.684 plugin-container[1638:7528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 16:33:54 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:33:54 INFO - PID 1636 | 2017-05-17 16:33:54.685 plugin-container[1638:7528] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:33:54 INFO - PID 1636 | __metrics Screen width/height:1600/1200 16:33:54 INFO - PID 1636 | colorDepth:24 16:33:54 INFO - PID 1636 | Browser inner width/height: 1024/691 16:33:54 INFO - PID 1636 | __metrics 16:33:55 INFO - TEST-INFO | 1636: exit 0 16:33:55 INFO - Browser initialized. 16:33:55 INFO - Running cycle 1/1 for dromaeo_css test... 16:33:55 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpZxlNRP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:57 INFO - PID 1639 | 2017-05-17 16:33:57.652 plugin-container[1640:7648] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 16:33:57 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:33:57 INFO - PID 1639 | 2017-05-17 16:33:57.653 plugin-container[1640:7648] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 16:33:57 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:33:57 INFO - PID 1639 | 2017-05-17 16:33:57.653 plugin-container[1640:7648] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:33:58 INFO - PID 1639 | 2017-05-17 16:33:58.360 plugin-container[1641:7696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 16:33:58 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:33:58 INFO - PID 1639 | 2017-05-17 16:33:58.361 plugin-container[1641:7696] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 16:33:58 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:33:58 INFO - PID 1639 | 2017-05-17 16:33:58.361 plugin-container[1641:7696] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:34:07 INFO - PID 1639 | RSS: Main: 249372672 16:34:07 INFO - PID 1639 | 16:37:10 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 16:37:11 INFO - PID 1639 | RSS: Main: 259121152 16:37:11 INFO - PID 1639 | 16:40:13 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 16:40:14 INFO - PID 1639 | RSS: Main: 267505664 16:40:14 INFO - PID 1639 | 16:43:15 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 16:43:16 INFO - PID 1639 | RSS: Main: 271953920 16:43:16 INFO - PID 1639 | 16:46:18 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 16:46:18 INFO - PID 1639 | RSS: Main: 271425536 16:46:18 INFO - PID 1639 | 16:49:21 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 16:49:22 INFO - PID 1639 | RSS: Main: 272392192 16:49:22 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 16:52:24 INFO - PID 1639 | RSS: Main: 273575936 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | __start_tp_report 16:52:24 INFO - PID 1639 | _x_x_mozilla_page_load 16:52:24 INFO - PID 1639 | _x_x_mozilla_page_load_details 16:52:24 INFO - PID 1639 | |i|pagename|runs| 16:52:24 INFO - PID 1639 | |0;dojo.html;3195.904122876314;3640.6177351378087;3665.413533834587;3667.8166091695407;3675.6875665568427;5166.689998600086;6127.1284443912755;6233.937660623393;6237.656928868911;6262.780802671901;6169.969150154254;6227.782027629034;6241.1574437451045;6278.780242691507;6292.5595208335435;4871.683340582868;5177.223416487519;5435.402105768362;5543.6119471637;5637.379888212294;5392.2181283713835;5554.389017208104;5623.072193088156;5632;5670.376258611549;4608.884777880563;5516.365617953907;5535.280413546213;5575.665460072409;5588.888222235573;5392.541633961118;5465.961467321196;5497.670139791625;5567.582431317627;5576.553875689935;4143.647789937859;4306.698531102824;5489.313835770528;5500.5423562755905;5512.421195774426;6149.13912052313;6237.96881015592;6250.656213908233;6284.905726414107;6290.339514351019;6514.609123452608;6563.343665633399;6794.796156115324;6825.829354266133;6834.384905358542;6797.082393876813;6875.690593923286;6884.724611015554;6911.101556797635;6926.445884329241;6309.211348581427;6314.652694101823;6333.36666333363;6342.4926005919415;6353.983362662045;6773.695183716744;6933.5493192942295;7030.2969703029285;7034.50758446914;7044.718211271492;6496.447801936793;6578.901316480256;6626.370494802986;6663.13379260693;6677.198736151693;6820.931790682129;6827.453803695693;6839.179298484114;6841.042254084432;6853.520253582301;5903.940960590339;6230.470410015108;6618.569792963538;6668.366505182;6772.153480814898;5703.201551782754;6757.459403247728;6759.121314229217;6786.69459874297;6820.795376138723;5202;5432.130859062531;5435.592330575223;5446;5452.04589196889;4068.3083875741195;4744.4069491313585;4793.952060479421;4832.613390928717;4844.781984810692;6316.589421687576;6329.303776584572;6411.166548348685;6411.519136064814;6448.580842245239;6864.622445765431;6872.931270687329;6873.381395674313;6876.243613202544;6876.656167191721;6326.49388048955;6394.904076438857;6407.839804004937;6420.06908998202;6443.323451037667;5019.322391477104;5867.765289388462;5930.673812940329;5941.257342832146;6006;6998.860022799516;7021.473389495808;7051.612161331176;7061.858762824818;7079.716811327695;6784.151981002375;6835.521513494206;6859.725610975505;6862.691178896862;6863.450923925974;6713.227978782503;6868.141482314711;6968.651567421507;7049.08361912948;7076;6692.966535167293;6754;6779.762708305383;6782.830429239309;6791.694373753094;6806.42145417645;6850.760223392157;6873.621950792754;6879.828004299932;6887.6556172190185;5566.775309431919;6152.200213972155;6354.682265886595;6496.740130394731;6705.094812200486;5209.505097015777;6532.8366790830605;6618.007298905202;6638.668066596748;6662.06731057662;6450.870982580228;6521.771737989147;6549.541532092708;6554.672266386757;6579.144711187515;5484;6446.387593178641;6517.511186660925;6619.900701489385;6681.198256209286;6459.031145328238;6481;6483.157189565326;6488.156539649815;6535.379138981789 16:52:24 INFO - PID 1639 | |1;ext.html;676.8104930619427;773.0993392697508;837.8617528107862;1187.8099504079348;1293.598984314863;38953.415698764504;39318.60681393185;39339.40990885139;39392.409113863316;39528.80235598825;33814;39283;39794.204115917666;40270.39594406086;40645.983850403754;7023.543469674481;7992.120866704658;8287.129851365611;8460.703875364363;8467.364947628952;8486.405951583392;8604.913950860473;8610.698625548108;8620.741377758677;8625.964884213872;8500.78748031298;8573.314134869195;8623.09457506959;8635.870461943075;8653.394262401634;8538.914610853873;8583.699570515033;8595.742127736177;8606.784830379229;8607.698730544464;8327.250547450698;8397.664093436255;8446.113158118433;8453.196946290092;8475.94050743657;37823.054423639354;37828.05429864248;37886.86339409822;37900.43149352762;37911.24177516462;3645.7447978641508;3665.1753355495066;3686.3733165361955;3690.9077273068388;3698.537682789651;30982.53526197109;31085;31153.37693246145;31161;31313.8434307827;3113.3928883867657;3145.874165033396;3149.889753858604;3153.6373317068374;3158.273597072663;290262;291349;294983;297239;301909;38253;46530.30204546935;46661.06677866424;46875.4374387586;47152.5284747155;48021.75989120102;48177.75911120492;49006.77483062952;51125.65431938253;51129.977400452524;13586.45654173822;13608.115472494255;13640.181589104686;13651.976101792405;13668.794968075486;10761.085307748772;10815.026647601753;10822.567097316176;10827.971342722429;10843;13430.529931452353;13596;13609.52366667162;13621.863781362257;13689.31553422325;5919.319278283053;5965.13505541694;5967.433093856077;5971.7611295547695;5997.520198384119;1016.6594190945968;1041.1670663469195;1041.6614600254795;1069.1393428290146;1073.2004656530935;23282.53434931292;24283.635745463478;24471.877640612038;24473.632895506227;24590.508189836102;970.0396607358664;1142.4516232208587;1177.670252329349;1180.4215934192184;1190.315568548071;11345.262557933873;12358.258504489759;12377.009839212842;12484.188527745668;12513.74972500545;301369.9863001342;302855;303745.4812725922;303833;304043;54053.45946540563;54463.91969043999;54523.45476545184;54549.72725136428;54553;1365.7200273944015;1499.392745937897;1553.9611509712347;1560.017189170815;1566.9138197399252;17734;23950.52098958046;24168.15411460661;24237.757622424255;24302.63546046846;37511;37869.24261514809;37869.62130378661;37882.81058594689;38230.044248894;252068;252178;253012;253503;253920.0343981025;29729.554056689598;33211.50182747212;34365.48451773275;34543;34575.30849383049;38176;40984.975375615846;44441.66674999755;44865.551344486135;45162;29206.561901571917;30141.246468838453;30358.392832143676;30940.53589196209;31302.90439834595;30203;30572.541411878396;30828.53757193599;31064.844675776476;31155.844220778752;35151.29697406089;36310.91067267986;36788.08029799276;36806.815965919996;36974.8151259242;29499.96750113736;30565;31159.84420077885;31301.530477043318;31447.84276078605;22945.082196711945;23259.60442373463;23882.641760373957;23918.402039949142;24603.8769806157 16:52:24 INFO - PID 1639 | |2;jquery.html;382.1878508170138;430.8427423990245;433.9934900976484;445.48768915746876;456.68930170410925;21248.681269780966;21425.67861482079;21583.784162158394;22167.44581385466;22184.11263549458;20465.488362790937;20526.76839389638;21864.45338866529;22524.8737563122;22886.084556617716;5869.1196320551935;6061.787837425691;6127.325994140641;6131.846703832395;6132.601380910249;5788.957987562237;5993.9400605993815;6061.121137435091;6090.451859332659;6094.207752992127;5990.161377407145;6023.819285421422;6030.005049166883;6058.273007239138;6066.514678825705;5803.332616749065;5979.581429299951;5982.670953097578;5992.64044157352;6013.579049466495;4864.80540778372;5258.947410525922;5331.093714068617;5343.332083489564;5356.303680521546;19174.507314049086;20455.26130278928;26099.347516312053;26450.338741531425;26959.595606065926;2197.8021978022057;2230.2417178159503;2267.7958983691383;2279.5326957973743;2287.2909398086645;13964.790528142086;17530.29878804846;17558.64882702339;17911.3731019415;18004;1996.9301074462323;2006.8059504594744;2149.0866381787678;2173.739151301838;2185.519185779126;167640;169290.1535492327;170022;171579;172492.0630952671;11024.952629500185;12411.937940310241;12916.41876115577;13048.673783155307;13138;15522.456714014956;15560.92219538895;16926.57683557921;16933;16939.153042347833;7798.532088074734;8970.551472426483;9122.498262595489;9233.492157931378;9574.425534467815;8508.829823403497;8571.357148213909;8651.91348086511;8770.693025744069;8886.955565222133;6233.602686535849;7977.242161994601;7995.800104997422;8023.83952320962;8061.153578874134;2160.211522794169;2180.356794745554;2180.5529866377356;2184.2791878679996;2366.9289921302043;963.8843338799248;980.3774603126923;985.2905907746411;1002.2633364477103;1032.6024480574883;20325.390238292875;20871.686924696143;20933.37199884006;21292.935353232275;21309;970.9077637624414;985.4776968206862;1018.6791160784329;1023.370627064365;1028.4292217819032;10631.043206111488;10922.29005114665;10941.28881622692;10985.835212471819;11063.889361106447;522526;526821;536298.318508405;561148;561790;124139;126345.36827315805;127683.3615831915;128057.35971320084;128509.35745321214;1215.440897187286;1217.6225370135287;1253.141598005351;1257.6478585995935;1262.8926541244098;16994.150292485574;17579.472615821545;17938;17970.281188752302;18192.81807181964;53804.92390152253;54653;54827.17759233694;56648.433515664314;57319.42680573141;58679.11981320369;72675;78579.10656700884;79587;80070.99822504484;8722.171393717586;9012.459252444874;9057.818843623223;9061.68284110053;9086.772830679285;13501.392437340539;13717.519886803913;14294.142351458946;14459.204743739196;15217.934744567774;11611.361375124448;11817.290962542274;11962.76074478523;12150.331731754839;12190.390480476119;11221.495032723384;11325.207235493766;11479.655610331703;11544.820214673264;11625.476853541442;11899.286042837457;12149.02807775358;12269.75460490803;12286;12291.385430728607;12760.170588911691;12895.355232238539;12923.15999460032;13050.477980880658;13193.20840749558;11812.173147879566;11856.288622682667;11928.821067684165;12076;12100.152989291017 16:52:24 INFO - PID 1639 | |3;mootools.html;1240.5099985505724;1477.8596033376832;1479.5857159995205;1486.7472529669958;1490.3889405343812;11495.42522873856;11854.703632409193;11988.640340789769;12082.939585302083;12105.394730263484;9979.401235925832;11729.003034742036;11843.46704398302;11941.104417168726;11966.40167991599;2559.34736642156;2597.4025974025985;2611.4515951650083;2611.8171727979047;2612.1379944618325;2520.521100990815;2578.2523068310165;2583.0442736187542;2585.9224223273236;2589.4562141950214;2569.0366112707734;2579.239124458289;2598.44133511295;2611;2615.659964204651;2525.7979361651023;2549.2989427907287;2572.957952029431;2577.80666450017;2578.9871050644815;2463.507298540299;2551.1836212412036;2560.1807421625085;2567.691876974775;2571.6785401824773;11565;11815.232009919328;11903.988161006326;11916.34460104694;11920.642380728592;5690.9146362804595;6849.972504124371;6850.4862135340045;6859.211190713082;6860.348266914635;3908.804559772;4014.3376342903384;4316.374125751749;4344.63070638996;4396.098799746044;1065.5631191211567;1210.9343777475854;1252.993735031328;1261.0920137500984;1266.3351740336304;14914.62713432162;15340.999999999889;16508.25712842913;16559.751603725952;16679.999200047914;4737.763111844463;5075.086484432763;5554.500094991389;5775.5668324875805;5787.218725472091;11650.951414372747;11867.940660296643;11872.76254474923;11876.88123118775;11887.88112118885;6002.219711437572;6544.607323560602;6589.901151482732;6606.636634985122;6618.8345291368105;5851.239338885917;6016.669083200466;6066.090086487062;6201.255849298113;6274.341194174637;3976.9858686034877;4272.508661503967;4289.828406863753;4410.867673969786;4420.093880754438;1077.9784404312027;1286.2989670629343;1286.530416398008;1298.1886321049344;1303.5372442782866;642.4025656139835;652.7454292825706;752.6688257166829;772.5789444752623;800.2837460472961;5740.827775166752;6161.170552539135;6253.155823963798;6356.618602883842;6424.421802037839;597.4443767296456;598.2223109957039;603.3604379357895;610.8472881779555;611.3977731934042;3842.884713458601;4318.676099292566;4419.955800442019;4433.334999749999;4552.726836389827;11628.244164129304;14657;15095.924520377328;15110.54668359951;15165.772513412308;14326.856731432761;14891.032082914577;15071.698566028617;15077.321520531603;15084.924575377054;8389.916100839035;8442.451240669337;8590.140985901486;8599.61301741423;8603.225709686034;6103.633781973096;7158.6420678964805;7181.605011724405;7283.890741638986;7307.926920730724;11006.944965275123;12630.989520838495;12795.93602031984;12866.228026318451;12915.547955821501;11471.885281147415;13702.109362891779;13724.794128087888;13725.45098196061;13771.724565508834;9172.44965302084;9258.074192580689;9389.48357840298;9418.764530886783;9435.952820235854;10599.258051936291;10651.201159912882;10731.839022414826;10825.296355736851;10900.890991089987;9078.273738101068;9128.726138215865;9144.954275228582;9167.954160229156;9349.626014959325;9036;9074.31942604294;9084.863727043967;9085.90914090877;9160.816783664422;8203.671853125808;8338.166183381612;8346.582670866554;8354.164583541597;8355.791105222417;6028.517718582415;6068.575199736153;6144.539159562962;6192.2569291683485;6238.563300568917;6170.9074363885475;6195.2255968004;6265.404786545271;6292.685365731787;6365.681715914278 16:52:24 INFO - PID 1639 | |4;prototype.html;389.28954657749614;390.2156665103143;395.38912380372324;399.57844474079855;401.9658329042031;6108.358622344656;6131.601445906013;6141.447269745722;6156.5690401671845;6300.928842096843;5282.652923504501;5978.774351257993;6143.784967526138;6153.969230153843;6155.8768824623485;3652.579953305364;4257.8296868125235;4390.165868484994;4448.555144485558;4467.731936083837;3425.7088147507493;4398.824047038114;4421.182081314967;4450.9777451112695;4455.732656040632;3941.0344465605967;4143.668506519486;4402.669799765014;4416.734995900256;4423.181711383389;3778.882422001832;4355.194289056519;4360.585744354281;4412.294032954744;4422.469303683562;3128.2335827722127;3760.6615404613717;3769.3968964965475;3773.867914622975;3776.206996530732;5282.577393808486;5330.733463326818;5871.236739223874;5891.175235467038;5902.586818922677;4212.7472351659;5225.451327610622;5314.176302673053;5314.202869569557;5375.892482150335;5661.886762264731;6257.7184026718905;6552.082708420778;6578.210614726216;6585.473162147065;4326.069894972565;4328.329108988112;4329.502107257674;4337.306031035019;4357.847475338348;5774.335951365605;6722.596644201363;6920.688569014406;6971;6984.476164287648;3688.9710659137368;3874.5737968823405;4116.547179810218;4281.571842815693;4346.00041990344;4421.336799480104;4423.270160423558;4430.534793846664;4439.001224724476;4448.266036104006;4588;4968.205087186033;5179.19722443022;5256.526912577886;5258.474152584788;5079.288899554065;5743.253377061039;6530.575512591667;6555.704993275314;6565.835854103686;1491.403438624559;1497.977530337046;1505.3075585230918;1507.2915729607066;1509.2529198046818;983.9163671088038;986.6201512417771;1050.2543194331956;1187.3706935324292;1211.54567037361;5504.862378440571;5745.569082318842;6234.657093859882;6262.373762623702;6266.373362663697;5644.350899646511;5739.8852022960145;6738.831529211711;6817.931820681829;6858.828529286708;4630.4443466784205;5091.8981620367385;5420.83737487876;6183.81448556535;6257.374262573706;3191.5744034533923;3502.5972013218807;3503.3518799022263;3563.8396272167297;3583.8999265635557;5482.643628164156;6662.300458451793;6724.93275067243;6988.527342500804;7030.718771249195;6990.196127445409;6999.440044796404;7001.334873187039;7005.614691191931;7028.051213086168;6858.588484690935;6887.345702158287;6921.342472465007;6985.476089293222;7030.191527974246;5931.584789064724;6156.460035479707;6776.442367756967;6841.315868413119;6850.725970961105;5829;5888.411158884249;6612.50406219545;6644.335566443317;6738.630684657671;6574.76988305407;6895.4138898192205;7092.929070709433;7114.324139206767;7154.856902862018;4398.318260669601;4403.867883963486;4419.911601768011;4427.269500532376;4432.778361081869;6007.789727359696;6589.143411356493;6846.554973926679;6886.827829304107;6945.340192681687;5969.582129250911;6400.9039864403;6647.534672572873;6679.432248258758;6705.195376554844;5937.7921772737745;6268.558231606665;6346.492280617632;6472.67310201417;6558.442532384801;5873.559483038704;5991.520678345809;6472.838179045373;6498.122753428227;6508.60948343101;6365.490760739222;6604.7027883746305;6642.236142843635;6647.933520664731;6686.465082793462;5091.389033316026;6391.456726178327;6493.188351456068;6494.545381823416;6494.856697289789 16:52:24 INFO - PID 1639 | |5;yui.html;716.817211611039;828.9088200297963;859.8366310401025;860.6487814131812;863.7797361672773;14466.45571834902;15088.094714317149;16431.835681643195;16728.41450549231;16870.662586748258;12629.81055284169;13188.142770719896;17500.037497937607;17534.912325438356;17560.648787024253;948.7485816258697;965.1024547171128;967.690339091491;971.0386717150042;971.2278738402987;938.7277689470079;962.5091203486237;975.8975307592707;977.9266555008368;979.3487330924927;995.412706503163;1018.9962886556735;1030.340582027503;1037.092544023979;1039.3504059962524;926.5645146781002;1019.6176433837311;1027.6300531808542;1031.690492852145;1033.4212840809096;758.9696412143508;759.6771372166806;760.5740785160273;763.1605234242345;765.0933643632313;16458.506244812674;16818.495445136665;16979.23593438298;17318.480445586654;17588.91205543964;17248.310067597406;17273;17529.912350438168;17626.383076592258;17774.28902843885;14379.856201437957;14425.927870360581;14470.27648617565;14841.851581484156;14967.476138335214;3658.1037645776955;3668.1746607013474;3671.0822294426393;3673.430618254175;3684.078980254936;96804;118271.81728182694;120023.19965200529;120471.39764301122;121552;13008.154469959421;13344.866551334557;13402.128861624158;13472.932635336956;13515.459381624625;25132.497350052898;25365.87317063403;25629.3592660185;25890;26078;7758.418118641124;7782.610869456505;7800.336971357385;7831.804204894809;7847.293743563107;3311.9503207451908;3848.5574158971517;3883.5922228165637;3982.9601703983167;3990.521137463465;4290.184864875664;4368.497622773358;4378.190034843564;4379.4525684289465;4394.758288294111;924.3437159616612;936.9953150234205;940.6002448959165;946.3895787217207;950.1970834644715;645.773979107318;654.9279579246279;655.3872129558878;663.3234101216732;669.6283562622791;10104.898951010542;11162.441877906072;11785.175037747274;12011.819822702666;12039.337836418907;579.9246098007317;618.3445547719432;627.1533429869639;638.4732595608641;686.1491750229779;4545.681802273875;5273.789048438096;5321.3880403754065;5480.725963701799;5540.168974653834;71477;95411.04588954021;95654;96037.5198124019;96891;64123.358766412675;78642;78969;79104.20895791083;80836.59581702054;73174.63412682903;74616.62691686615;74803.62598186974;75635.62182189054;76769.61615192;10599.046085852311;11061.225714199929;11158.27471214401;11344.092472602337;11481.024112950492;24653;38039.66861159846;38409.00682911203;38753;39093.80453097716;49296.50703492919;49935;50238.74880625573;50699.493005069475;50907.490925091755;14050.297485125908;14143.43426262938;14371.928140359649;14583.343749531085;14637.487687930872;13383.92928565693;14374.568762937128;14406.855931440552;15664.451744188898;15878.364865405254;14152;14342.282885855457;14789.630259243606;14828.110313381232;14834.332455039765;14411.78382324245;14565.27173641335;14652.926735366254;14657.267136643339;14687.70624587481;12874.806877896652;13805.585832425044;14265.786013210018;14335.78496322577;14410.351534181196;8732.738017859474;9966.252531060054;10480.161587072864;10543;10847.294925829796;10434.791304173723;11346.886531134913;11573.826392604286;11661.125415594033;12023.398830058288 16:52:24 INFO - PID 1639 | __end_tp_report 16:52:24 INFO - PID 1639 | __start_cc_report 16:52:24 INFO - PID 1639 | _x_x_mozilla_cycle_collect,343 16:52:24 INFO - PID 1639 | __end_cc_report 16:52:24 INFO - PID 1639 | __startTimestamp1495065144265__endTimestamp 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | ------- Summary: start ------- 16:52:24 INFO - PID 1639 | Number of tests: 6 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6181.99 Median:6446.39 stddev:806.35 (12.5%) stddev-sans-first:774.05 16:52:24 INFO - PID 1639 | Values: 3195.9 3640.6 3665.4 3667.8 3675.7 5166.7 6127.1 6233.9 6237.7 6262.8 6170.0 6227.8 6241.2 6278.8 6292.6 4871.7 5177.2 5435.4 5543.6 5637.4 5392.2 5554.4 5623.1 5632.0 5670.4 4608.9 5516.4 5535.3 5575.7 5588.9 5392.5 5466.0 5497.7 5567.6 5576.6 4143.6 4306.7 5489.3 5500.5 5512.4 6149.1 6238.0 6250.7 6284.9 6290.3 6514.6 6563.3 6794.8 6825.8 6834.4 6797.1 6875.7 6884.7 6911.1 6926.4 6309.2 6314.7 6333.4 6342.5 6354.0 6773.7 6933.5 7030.3 7034.5 7044.7 6496.4 6578.9 6626.4 6663.1 6677.2 6820.9 6827.5 6839.2 6841.0 6853.5 5903.9 6230.5 6618.6 6668.4 6772.2 5703.2 6757.5 6759.1 6786.7 6820.8 5202.0 5432.1 5435.6 5446.0 5452.0 4068.3 4744.4 4794.0 4832.6 4844.8 6316.6 6329.3 6411.2 6411.5 6448.6 6864.6 6872.9 6873.4 6876.2 6876.7 6326.5 6394.9 6407.8 6420.1 6443.3 5019.3 5867.8 5930.7 5941.3 6006.0 6998.9 7021.5 7051.6 7061.9 7079.7 6784.2 6835.5 6859.7 6862.7 6863.5 6713.2 6868.1 6968.7 7049.1 7076.0 6693.0 6754.0 6779.8 6782.8 6791.7 6806.4 6850.8 6873.6 6879.8 6887.7 5566.8 6152.2 6354.7 6496.7 6705.1 5209.5 6532.8 6618.0 6638.7 6662.1 6450.9 6521.8 6549.5 6554.7 6579.1 5484.0 6446.4 6517.5 6619.9 6681.2 6459.0 6481.0 6483.2 6488.2 6535.4 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:43620.18 Median:3155.96 stddev:74500.53 (2360.6%) stddev-sans-first:74639.75 16:52:24 INFO - PID 1639 | Values: 676.8 773.1 837.9 1187.8 1293.6 38953.4 39318.6 39339.4 39392.4 39528.8 33814.0 39283.0 39794.2 40270.4 40646.0 7023.5 7992.1 8287.1 8460.7 8467.4 8486.4 8604.9 8610.7 8620.7 8626.0 8500.8 8573.3 8623.1 8635.9 8653.4 8538.9 8583.7 8595.7 8606.8 8607.7 8327.3 8397.7 8446.1 8453.2 8475.9 37823.1 37828.1 37886.9 37900.4 37911.2 3645.7 3665.2 3686.4 3690.9 3698.5 30982.5 31085.0 31153.4 31161.0 31313.8 3113.4 3145.9 3149.9 3153.6 3158.3 290262.0 291349.0 294983.0 297239.0 301909.0 38253.0 46530.3 46661.1 46875.4 47152.5 48021.8 48177.8 49006.8 51125.7 51130.0 13586.5 13608.1 13640.2 13652.0 13668.8 10761.1 10815.0 10822.6 10828.0 10843.0 13430.5 13596.0 13609.5 13621.9 13689.3 5919.3 5965.1 5967.4 5971.8 5997.5 1016.7 1041.2 1041.7 1069.1 1073.2 23282.5 24283.6 24471.9 24473.6 24590.5 970.0 1142.5 1177.7 1180.4 1190.3 11345.3 12358.3 12377.0 12484.2 12513.7 301370.0 302855.0 303745.5 303833.0 304043.0 54053.5 54463.9 54523.5 54549.7 54553.0 1365.7 1499.4 1554.0 1560.0 1566.9 17734.0 23950.5 24168.2 24237.8 24302.6 37511.0 37869.2 37869.6 37882.8 38230.0 252068.0 252178.0 253012.0 253503.0 253920.0 29729.6 33211.5 34365.5 34543.0 34575.3 38176.0 40985.0 44441.7 44865.6 45162.0 29206.6 30141.2 30358.4 30940.5 31302.9 30203.0 30572.5 30828.5 31064.8 31155.8 35151.3 36310.9 36788.1 36806.8 36974.8 29500.0 30565.0 31159.8 31301.5 31447.8 22945.1 23259.6 23882.6 23918.4 24603.9 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:35539.54 Median:2166.98 stddev:92527.35 (4269.9%) stddev-sans-first:92749.25 16:52:24 INFO - PID 1639 | Values: 382.2 430.8 434.0 445.5 456.7 21248.7 21425.7 21583.8 22167.4 22184.1 20465.5 20526.8 21864.5 22524.9 22886.1 5869.1 6061.8 6127.3 6131.8 6132.6 5789.0 5993.9 6061.1 6090.5 6094.2 5990.2 6023.8 6030.0 6058.3 6066.5 5803.3 5979.6 5982.7 5992.6 6013.6 4864.8 5258.9 5331.1 5343.3 5356.3 19174.5 20455.3 26099.3 26450.3 26959.6 2197.8 2230.2 2267.8 2279.5 2287.3 13964.8 17530.3 17558.6 17911.4 18004.0 1996.9 2006.8 2149.1 2173.7 2185.5 167640.0 169290.2 170022.0 171579.0 172492.1 11025.0 12411.9 12916.4 13048.7 13138.0 15522.5 15560.9 16926.6 16933.0 16939.2 7798.5 8970.6 9122.5 9233.5 9574.4 8508.8 8571.4 8651.9 8770.7 8887.0 6233.6 7977.2 7995.8 8023.8 8061.2 2160.2 2180.4 2180.6 2184.3 2366.9 963.9 980.4 985.3 1002.3 1032.6 20325.4 20871.7 20933.4 21292.9 21309.0 970.9 985.5 1018.7 1023.4 1028.4 10631.0 10922.3 10941.3 10985.8 11063.9 522526.0 526821.0 536298.3 561148.0 561790.0 124139.0 126345.4 127683.4 128057.4 128509.4 1215.4 1217.6 1253.1 1257.6 1262.9 16994.2 17579.5 17938.0 17970.3 18192.8 53804.9 54653.0 54827.2 56648.4 57319.4 58679.1 72675.0 78579.1 79587.0 80071.0 8722.2 9012.5 9057.8 9061.7 9086.8 13501.4 13717.5 14294.1 14459.2 15217.9 11611.4 11817.3 11962.8 12150.3 12190.4 11221.5 11325.2 11479.7 11544.8 11625.5 11899.3 12149.0 12269.8 12286.0 12291.4 12760.2 12895.4 12923.2 13050.5 13193.2 11812.2 11856.3 11928.8 12076.0 12100.2 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:7061.34 Median:3942.90 stddev:4418.56 (112.1%) stddev-sans-first:4409.30 16:52:24 INFO - PID 1639 | Values: 1240.5 1477.9 1479.6 1486.7 1490.4 11495.4 11854.7 11988.6 12082.9 12105.4 9979.4 11729.0 11843.5 11941.1 11966.4 2559.3 2597.4 2611.5 2611.8 2612.1 2520.5 2578.3 2583.0 2585.9 2589.5 2569.0 2579.2 2598.4 2611.0 2615.7 2525.8 2549.3 2573.0 2577.8 2579.0 2463.5 2551.2 2560.2 2567.7 2571.7 11565.0 11815.2 11904.0 11916.3 11920.6 5690.9 6850.0 6850.5 6859.2 6860.3 3908.8 4014.3 4316.4 4344.6 4396.1 1065.6 1210.9 1253.0 1261.1 1266.3 14914.6 15341.0 16508.3 16559.8 16680.0 4737.8 5075.1 5554.5 5775.6 5787.2 11651.0 11867.9 11872.8 11876.9 11887.9 6002.2 6544.6 6589.9 6606.6 6618.8 5851.2 6016.7 6066.1 6201.3 6274.3 3977.0 4272.5 4289.8 4410.9 4420.1 1078.0 1286.3 1286.5 1298.2 1303.5 642.4 652.7 752.7 772.6 800.3 5740.8 6161.2 6253.2 6356.6 6424.4 597.4 598.2 603.4 610.8 611.4 3842.9 4318.7 4420.0 4433.3 4552.7 11628.2 14657.0 15095.9 15110.5 15165.8 14326.9 14891.0 15071.7 15077.3 15084.9 8389.9 8442.5 8590.1 8599.6 8603.2 6103.6 7158.6 7181.6 7283.9 7307.9 11006.9 12631.0 12795.9 12866.2 12915.5 11471.9 13702.1 13724.8 13725.5 13771.7 9172.4 9258.1 9389.5 9418.8 9436.0 10599.3 10651.2 10731.8 10825.3 10900.9 9078.3 9128.7 9145.0 9168.0 9349.6 9036.0 9074.3 9084.9 9085.9 9160.8 8203.7 8338.2 8346.6 8354.2 8355.8 6028.5 6068.6 6144.5 6192.3 6238.6 6170.9 6195.2 6265.4 6292.7 6365.7 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5224.12 Median:5872.40 stddev:1681.09 (28.6%) stddev-sans-first:1646.17 16:52:24 INFO - PID 1639 | Values: 389.3 390.2 395.4 399.6 402.0 6108.4 6131.6 6141.4 6156.6 6300.9 5282.7 5978.8 6143.8 6154.0 6155.9 3652.6 4257.8 4390.2 4448.6 4467.7 3425.7 4398.8 4421.2 4451.0 4455.7 3941.0 4143.7 4402.7 4416.7 4423.2 3778.9 4355.2 4360.6 4412.3 4422.5 3128.2 3760.7 3769.4 3773.9 3776.2 5282.6 5330.7 5871.2 5891.2 5902.6 4212.7 5225.5 5314.2 5314.2 5375.9 5661.9 6257.7 6552.1 6578.2 6585.5 4326.1 4328.3 4329.5 4337.3 4357.8 5774.3 6722.6 6920.7 6971.0 6984.5 3689.0 3874.6 4116.5 4281.6 4346.0 4421.3 4423.3 4430.5 4439.0 4448.3 4588.0 4968.2 5179.2 5256.5 5258.5 5079.3 5743.3 6530.6 6555.7 6565.8 1491.4 1498.0 1505.3 1507.3 1509.3 983.9 986.6 1050.3 1187.4 1211.5 5504.9 5745.6 6234.7 6262.4 6266.4 5644.4 5739.9 6738.8 6817.9 6858.8 4630.4 5091.9 5420.8 6183.8 6257.4 3191.6 3502.6 3503.4 3563.8 3583.9 5482.6 6662.3 6724.9 6988.5 7030.7 6990.2 6999.4 7001.3 7005.6 7028.1 6858.6 6887.3 6921.3 6985.5 7030.2 5931.6 6156.5 6776.4 6841.3 6850.7 5829.0 5888.4 6612.5 6644.3 6738.6 6574.8 6895.4 7092.9 7114.3 7154.9 4398.3 4403.9 4419.9 4427.3 4432.8 6007.8 6589.1 6846.6 6886.8 6945.3 5969.6 6400.9 6647.5 6679.4 6705.2 5937.8 6268.6 6346.5 6472.7 6558.4 5873.6 5991.5 6472.8 6498.1 6508.6 6365.5 6604.7 6642.2 6647.9 6686.5 5091.4 6391.5 6493.2 6494.5 6494.9 16:52:24 INFO - PID 1639 | 16:52:24 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:19791.99 Median:14694.98 stddev:27388.12 (186.4%) stddev-sans-first:27427.50 16:52:24 INFO - PID 1639 | Values: 716.8 828.9 859.8 860.6 863.8 14466.5 15088.1 16431.8 16728.4 16870.7 12629.8 13188.1 17500.0 17534.9 17560.6 948.7 965.1 967.7 971.0 971.2 938.7 962.5 975.9 977.9 979.3 995.4 1019.0 1030.3 1037.1 1039.4 926.6 1019.6 1027.6 1031.7 1033.4 759.0 759.7 760.6 763.2 765.1 16458.5 16818.5 16979.2 17318.5 17588.9 17248.3 17273.0 17529.9 17626.4 17774.3 14379.9 14425.9 14470.3 14841.9 14967.5 3658.1 3668.2 3671.1 3673.4 3684.1 96804.0 118271.8 120023.2 120471.4 121552.0 13008.2 13344.9 13402.1 13472.9 13515.5 25132.5 25365.9 25629.4 25890.0 26078.0 7758.4 7782.6 7800.3 7831.8 7847.3 3312.0 3848.6 3883.6 3983.0 3990.5 4290.2 4368.5 4378.2 4379.5 4394.8 924.3 937.0 940.6 946.4 950.2 645.8 654.9 655.4 663.3 669.6 10104.9 11162.4 11785.2 12011.8 12039.3 579.9 618.3 627.2 638.5 686.1 4545.7 5273.8 5321.4 5480.7 5540.2 71477.0 95411.0 95654.0 96037.5 96891.0 64123.4 78642.0 78969.0 79104.2 80836.6 73174.6 74616.6 74803.6 75635.6 76769.6 10599.0 11061.2 11158.3 11344.1 11481.0 24653.0 38039.7 38409.0 38753.0 39093.8 49296.5 49935.0 50238.7 50699.5 50907.5 14050.3 14143.4 14371.9 14583.3 14637.5 13383.9 14374.6 14406.9 15664.5 15878.4 14152.0 14342.3 14789.6 14828.1 14834.3 14411.8 14565.3 14652.9 14657.3 14687.7 12874.8 13805.6 14265.8 14335.8 14410.4 8732.7 9966.3 10480.2 10543.0 10847.3 10434.8 11346.9 11573.8 11661.1 12023.4 16:52:24 INFO - PID 1639 | -------- Summary: end -------- 16:52:24 INFO - PID 1639 | 16:52:24 INFO - TEST-INFO | 1639: exit 0 16:52:24 INFO - TEST-OK | dromaeo_css | took 1112565ms 16:52:24 INFO - TEST-START | kraken 16:52:24 INFO - operating with platform_type : mac_ 16:52:24 INFO - Initialising browser for kraken test... 16:52:24 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpDbCUVz/profile http://localhost:49229/getInfo.html) 16:52:25 INFO - PID 1655 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2939: String.localeCompare is deprecated; use String.prototype.localeCompare instead 16:52:27 INFO - PID 1655 | 2017-05-17 16:52:27.290 plugin-container[1656:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 16:52:27 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:27 INFO - PID 1655 | 2017-05-17 16:52:27.293 plugin-container[1656:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 16:52:27 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:27 INFO - PID 1655 | 2017-05-17 16:52:27.293 plugin-container[1656:10201] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:52:27 INFO - PID 1655 | __metrics Screen width/height:1600/1200 16:52:27 INFO - PID 1655 | colorDepth:24 16:52:27 INFO - PID 1655 | Browser inner width/height: 1024/691 16:52:27 INFO - PID 1655 | __metrics 16:52:27 INFO - PID 1655 | 2017-05-17 16:52:27.801 plugin-container[1657:10256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9433, name = 'com.apple.tsm.portname' 16:52:27 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:27 INFO - PID 1655 | 2017-05-17 16:52:27.802 plugin-container[1657:10256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 16:52:27 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:27 INFO - PID 1655 | 2017-05-17 16:52:27.802 plugin-container[1657:10256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:52:27 INFO - PID 1655 | 16:52:27 INFO - PID 1655 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 16:52:27 INFO - PID 1655 | 16:52:28 INFO - TEST-INFO | 1655: exit 0 16:52:28 INFO - Browser initialized. 16:52:28 INFO - Running cycle 1/1 for kraken test... 16:52:28 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpDbCUVz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:52:30 INFO - PID 1658 | 2017-05-17 16:52:30.285 plugin-container[1659:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c4b, name = 'com.apple.tsm.portname' 16:52:30 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:30 INFO - PID 1658 | 2017-05-17 16:52:30.286 plugin-container[1659:10355] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 16:52:30 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:30 INFO - PID 1658 | 2017-05-17 16:52:30.286 plugin-container[1659:10355] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:52:30 INFO - PID 1658 | 2017-05-17 16:52:30.940 plugin-container[1660:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 16:52:30 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:30 INFO - PID 1658 | 2017-05-17 16:52:30.940 plugin-container[1660:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 16:52:30 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:52:30 INFO - PID 1658 | 2017-05-17 16:52:30.940 plugin-container[1660:10400] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:52:40 INFO - PID 1658 | RSS: Main: 239968256 16:52:40 INFO - PID 1658 | 16:52:42 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 16:52:43 INFO - PID 1658 | RSS: Main: 252190720 16:52:43 INFO - PID 1658 | 16:52:46 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 16:52:46 INFO - PID 1658 | RSS: Main: 260235264 16:52:46 INFO - PID 1658 | 16:52:50 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 16:52:51 INFO - PID 1658 | RSS: Main: 261914624 16:52:51 INFO - PID 1658 | 16:52:52 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 16:52:53 INFO - PID 1658 | RSS: Main: 262963200 16:52:53 INFO - PID 1658 | 16:52:55 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:52:56 INFO - PID 1658 | RSS: Main: 263352320 16:52:56 INFO - PID 1658 | 16:52:58 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 16:52:59 INFO - PID 1658 | RSS: Main: 270094336 16:52:59 INFO - PID 1658 | 16:53:02 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 16:53:03 INFO - PID 1658 | RSS: Main: 269725696 16:53:03 INFO - PID 1658 | 16:53:06 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 16:53:06 INFO - PID 1658 | RSS: Main: 268955648 16:53:06 INFO - PID 1658 | 16:53:08 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:53:08 INFO - PID 1658 | RSS: Main: 268820480 16:53:08 INFO - PID 1658 | 16:53:10 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:53:11 INFO - PID 1658 | RSS: Main: 268632064 16:53:11 INFO - PID 1658 | 16:53:13 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:53:13 INFO - PID 1658 | RSS: Main: 268775424 16:53:13 INFO - PID 1658 | 16:53:16 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:53:17 INFO - PID 1658 | RSS: Main: 268390400 16:53:17 INFO - PID 1658 | 16:53:20 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:53:21 INFO - PID 1658 | RSS: Main: 268656640 16:53:21 INFO - PID 1658 | 16:53:22 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 16:53:23 INFO - PID 1658 | RSS: Main: 270848000 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | __start_tp_report 16:53:23 INFO - PID 1658 | _x_x_mozilla_page_load 16:53:23 INFO - PID 1658 | _x_x_mozilla_page_load_details 16:53:23 INFO - PID 1658 | |i|pagename|runs| 16:53:23 INFO - PID 1658 | |0;ai-astar;103;114;116;115;114;115;115;114;115;114 16:53:23 INFO - PID 1658 | |1;audio-beat-detection;147;110;121;156;113;112;112;114;140;110 16:53:23 INFO - PID 1658 | |2;audio-dft;199;203;195;200;227;261;235;244;265;203 16:53:23 INFO - PID 1658 | |3;audio-fft;79;61;79;89;59;59;57;57;80;57 16:53:23 INFO - PID 1658 | |4;audio-oscillator;86;70;82;73;68;68;87;72;69;91 16:53:23 INFO - PID 1658 | |5;imaging-gaussian-blur;95;92;92;90;116;90;91;92;92;91 16:53:23 INFO - PID 1658 | |6;imaging-darkroom;98;100;97;98;148;98;99;98;97;99 16:53:23 INFO - PID 1658 | |7;imaging-desaturate;91;93;92;90;100;90;91;92;91;91 16:53:23 INFO - PID 1658 | |8;json-parse-financial;50;49;49;49;52;50;50;52;50;50 16:53:23 INFO - PID 1658 | |9;json-stringify-tinderbox;60;63;62;60;63;60;56;59;57;56 16:53:23 INFO - PID 1658 | |10;stanford-crypto-aes;84;81;81;82;84;82;87;84;83;81 16:53:23 INFO - PID 1658 | |11;stanford-crypto-ccm;142;145;150;137;137;138;138;155;176;173 16:53:23 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;222;235;183;187;182;195;204;182;185;211 16:53:23 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;71;71;66;70;66;70;67;68;67;70 16:53:23 INFO - PID 1658 | __end_tp_report 16:53:23 INFO - PID 1658 | __start_cc_report 16:53:23 INFO - PID 1658 | _x_x_mozilla_cycle_collect,934 16:53:23 INFO - PID 1658 | __end_cc_report 16:53:23 INFO - PID 1658 | __startTimestamp1495065203148__endTimestamp 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | ------- Summary: start ------- 16:53:23 INFO - PID 1658 | Number of tests: 14 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:113.50 Median:115.00 stddev:3.75 (3.3%) stddev-sans-first:0.71 16:53:23 INFO - PID 1658 | Values: 103.0 114.0 116.0 115.0 114.0 115.0 115.0 114.0 115.0 114.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:117.50 stddev:17.37 (14.8%) stddev-sans-first:16.21 16:53:23 INFO - PID 1658 | Values: 147.0 110.0 121.0 156.0 113.0 112.0 112.0 114.0 140.0 110.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:223.20 Median:231.00 stddev:26.87 (11.6%) stddev-sans-first:27.03 16:53:23 INFO - PID 1658 | Values: 199.0 203.0 195.0 200.0 227.0 261.0 235.0 244.0 265.0 203.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:67.70 Median:70.00 stddev:12.47 (17.8%) stddev-sans-first:12.54 16:53:23 INFO - PID 1658 | Values: 79.0 61.0 79.0 89.0 59.0 59.0 57.0 57.0 80.0 57.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:77.50 stddev:8.92 (11.5%) stddev-sans-first:8.79 16:53:23 INFO - PID 1658 | Values: 86.0 70.0 82.0 73.0 68.0 68.0 87.0 72.0 69.0 91.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:92.00 stddev:7.82 (8.5%) stddev-sans-first:8.29 16:53:23 INFO - PID 1658 | Values: 95.0 92.0 92.0 90.0 116.0 90.0 91.0 92.0 92.0 91.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:98.00 stddev:15.77 (16.1%) stddev-sans-first:16.61 16:53:23 INFO - PID 1658 | Values: 98.0 100.0 97.0 98.0 148.0 98.0 99.0 98.0 97.0 99.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:91.00 stddev:2.92 (3.2%) stddev-sans-first:3.07 16:53:23 INFO - PID 1658 | Values: 91.0 93.0 92.0 90.0 100.0 90.0 91.0 92.0 91.0 91.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:1.10 (2.2%) stddev-sans-first:1.17 16:53:23 INFO - PID 1658 | Values: 50.0 49.0 49.0 49.0 52.0 50.0 50.0 52.0 50.0 50.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:2.63 (4.4%) stddev-sans-first:2.79 16:53:23 INFO - PID 1658 | Values: 60.0 63.0 62.0 60.0 63.0 60.0 56.0 59.0 57.0 56.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.50 stddev:1.91 (2.3%) stddev-sans-first:1.99 16:53:23 INFO - PID 1658 | Values: 84.0 81.0 81.0 82.0 84.0 82.0 87.0 84.0 83.0 81.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:147.50 stddev:14.67 (9.9%) stddev-sans-first:15.33 16:53:23 INFO - PID 1658 | Values: 142.0 145.0 150.0 137.0 137.0 138.0 138.0 155.0 176.0 173.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.60 Median:199.50 stddev:18.80 (9.4%) stddev-sans-first:17.94 16:53:23 INFO - PID 1658 | Values: 222.0 235.0 183.0 187.0 182.0 195.0 204.0 182.0 185.0 211.0 16:53:23 INFO - PID 1658 | 16:53:23 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:70.00 stddev:2.01 (2.9%) stddev-sans-first:1.94 16:53:23 INFO - PID 1658 | Values: 71.0 71.0 66.0 70.0 66.0 70.0 67.0 68.0 67.0 70.0 16:53:23 INFO - PID 1658 | -------- Summary: end -------- 16:53:23 INFO - PID 1658 | 16:53:23 INFO - TEST-INFO | 1658: exit 0 16:53:24 INFO - TEST-OK | kraken | took 59144ms 16:53:24 INFO - SUITE-END | took 1171s 16:53:24 INFO - Completed test suite (00:19:32) 16:53:24 INFO - javascript benchmark 16:53:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7777.749600193763, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3195.904122876314, 3640.6177351378087, 3665.413533834587, 3667.8166091695407, 3675.6875665568427, 5166.689998600086, 6127.1284443912755, 6233.937660623393, 6237.656928868911, 6262.780802671901, 6169.969150154254, 6227.782027629034, 6241.1574437451045, 6278.780242691507, 6292.5595208335435, 4871.683340582868, 5177.223416487519, 5435.402105768362, 5543.6119471637, 5637.379888212294, 5392.2181283713835, 5554.389017208104, 5623.072193088156, 5632.0, 5670.376258611549, 4608.884777880563, 5516.365617953907, 5535.280413546213, 5575.665460072409, 5588.888222235573, 5392.541633961118, 5465.961467321196, 5497.670139791625, 5567.582431317627, 5576.553875689935, 4143.647789937859, 4306.698531102824, 5489.313835770528, 5500.5423562755905, 5512.421195774426, 6149.13912052313, 6237.96881015592, 6250.656213908233, 6284.905726414107, 6290.339514351019, 6514.609123452608, 6563.343665633399, 6794.796156115324, 6825.829354266133, 6834.384905358542, 6797.082393876813, 6875.690593923286, 6884.724611015554, 6911.101556797635, 6926.445884329241, 6309.211348581427, 6314.652694101823, 6333.36666333363, 6342.4926005919415, 6353.983362662045, 6773.695183716744, 6933.5493192942295, 7030.2969703029285, 7034.50758446914, 7044.718211271492, 6496.447801936793, 6578.901316480256, 6626.370494802986, 6663.13379260693, 6677.198736151693, 6820.931790682129, 6827.453803695693, 6839.179298484114, 6841.042254084432, 6853.520253582301, 5903.940960590339, 6230.470410015108, 6618.569792963538, 6668.366505182, 6772.153480814898, 5703.201551782754, 6757.459403247728, 6759.121314229217, 6786.69459874297, 6820.795376138723, 5202.0, 5432.130859062531, 5435.592330575223, 5446.0, 5452.04589196889, 4068.3083875741195, 4744.4069491313585, 4793.952060479421, 4832.613390928717, 4844.781984810692, 6316.589421687576, 6329.303776584572, 6411.166548348685, 6411.519136064814, 6448.580842245239, 6864.622445765431, 6872.931270687329, 6873.381395674313, 6876.243613202544, 6876.656167191721, 6326.49388048955, 6394.904076438857, 6407.839804004937, 6420.06908998202, 6443.323451037667, 5019.322391477104, 5867.765289388462, 5930.673812940329, 5941.257342832146, 6006.0, 6998.860022799516, 7021.473389495808, 7051.612161331176, 7061.858762824818, 7079.716811327695, 6784.151981002375, 6835.521513494206, 6859.725610975505, 6862.691178896862, 6863.450923925974, 6713.227978782503, 6868.141482314711, 6968.651567421507, 7049.08361912948, 7076.0, 6692.966535167293, 6754.0, 6779.762708305383, 6782.830429239309, 6791.694373753094, 6806.42145417645, 6850.760223392157, 6873.621950792754, 6879.828004299932, 6887.6556172190185, 5566.775309431919, 6152.200213972155, 6354.682265886595, 6496.740130394731, 6705.094812200486, 5209.505097015777, 6532.8366790830605, 6618.007298905202, 6638.668066596748, 6662.06731057662, 6450.870982580228, 6521.771737989147, 6549.541532092708, 6554.672266386757, 6579.144711187515, 5484.0, 6446.387593178641, 6517.511186660925, 6619.900701489385, 6681.198256209286, 6459.031145328238, 6481.0, 6483.157189565326, 6488.156539649815, 6535.379138981789], "value": 6126.68018285777, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.8104930619427, 773.0993392697508, 837.8617528107862, 1187.8099504079348, 1293.598984314863, 38953.415698764504, 39318.60681393185, 39339.40990885139, 39392.409113863316, 39528.80235598825, 33814.0, 39283.0, 39794.204115917666, 40270.39594406086, 40645.983850403754, 7023.543469674481, 7992.120866704658, 8287.129851365611, 8460.703875364363, 8467.364947628952, 8486.405951583392, 8604.913950860473, 8610.698625548108, 8620.741377758677, 8625.964884213872, 8500.78748031298, 8573.314134869195, 8623.09457506959, 8635.870461943075, 8653.394262401634, 8538.914610853873, 8583.699570515033, 8595.742127736177, 8606.784830379229, 8607.698730544464, 8327.250547450698, 8397.664093436255, 8446.113158118433, 8453.196946290092, 8475.94050743657, 37823.054423639354, 37828.05429864248, 37886.86339409822, 37900.43149352762, 37911.24177516462, 3645.7447978641508, 3665.1753355495066, 3686.3733165361955, 3690.9077273068388, 3698.537682789651, 30982.53526197109, 31085.0, 31153.37693246145, 31161.0, 31313.8434307827, 3113.3928883867657, 3145.874165033396, 3149.889753858604, 3153.6373317068374, 3158.273597072663, 290262.0, 291349.0, 294983.0, 297239.0, 301909.0, 38253.0, 46530.30204546935, 46661.06677866424, 46875.4374387586, 47152.5284747155, 48021.75989120102, 48177.75911120492, 49006.77483062952, 51125.65431938253, 51129.977400452524, 13586.45654173822, 13608.115472494255, 13640.181589104686, 13651.976101792405, 13668.794968075486, 10761.085307748772, 10815.026647601753, 10822.567097316176, 10827.971342722429, 10843.0, 13430.529931452353, 13596.0, 13609.52366667162, 13621.863781362257, 13689.31553422325, 5919.319278283053, 5965.13505541694, 5967.433093856077, 5971.7611295547695, 5997.520198384119, 1016.6594190945968, 1041.1670663469195, 1041.6614600254795, 1069.1393428290146, 1073.2004656530935, 23282.53434931292, 24283.635745463478, 24471.877640612038, 24473.632895506227, 24590.508189836102, 970.0396607358664, 1142.4516232208587, 1177.670252329349, 1180.4215934192184, 1190.315568548071, 11345.262557933873, 12358.258504489759, 12377.009839212842, 12484.188527745668, 12513.74972500545, 301369.9863001342, 302855.0, 303745.4812725922, 303833.0, 304043.0, 54053.45946540563, 54463.91969043999, 54523.45476545184, 54549.72725136428, 54553.0, 1365.7200273944015, 1499.392745937897, 1553.9611509712347, 1560.017189170815, 1566.9138197399252, 17734.0, 23950.52098958046, 24168.15411460661, 24237.757622424255, 24302.63546046846, 37511.0, 37869.24261514809, 37869.62130378661, 37882.81058594689, 38230.044248894, 252068.0, 252178.0, 253012.0, 253503.0, 253920.0343981025, 29729.554056689598, 33211.50182747212, 34365.48451773275, 34543.0, 34575.30849383049, 38176.0, 40984.975375615846, 44441.66674999755, 44865.551344486135, 45162.0, 29206.561901571917, 30141.246468838453, 30358.392832143676, 30940.53589196209, 31302.90439834595, 30203.0, 30572.541411878396, 30828.53757193599, 31064.844675776476, 31155.844220778752, 35151.29697406089, 36310.91067267986, 36788.08029799276, 36806.815965919996, 36974.8151259242, 29499.96750113736, 30565.0, 31159.84420077885, 31301.530477043318, 31447.84276078605, 22945.082196711945, 23259.60442373463, 23882.641760373957, 23918.402039949142, 24603.8769806157], "value": 17398.80734338903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.1878508170138, 430.8427423990245, 433.9934900976484, 445.48768915746876, 456.68930170410925, 21248.681269780966, 21425.67861482079, 21583.784162158394, 22167.44581385466, 22184.11263549458, 20465.488362790937, 20526.76839389638, 21864.45338866529, 22524.8737563122, 22886.084556617716, 5869.1196320551935, 6061.787837425691, 6127.325994140641, 6131.846703832395, 6132.601380910249, 5788.957987562237, 5993.9400605993815, 6061.121137435091, 6090.451859332659, 6094.207752992127, 5990.161377407145, 6023.819285421422, 6030.005049166883, 6058.273007239138, 6066.514678825705, 5803.332616749065, 5979.581429299951, 5982.670953097578, 5992.64044157352, 6013.579049466495, 4864.80540778372, 5258.947410525922, 5331.093714068617, 5343.332083489564, 5356.303680521546, 19174.507314049086, 20455.26130278928, 26099.347516312053, 26450.338741531425, 26959.595606065926, 2197.8021978022057, 2230.2417178159503, 2267.7958983691383, 2279.5326957973743, 2287.2909398086645, 13964.790528142086, 17530.29878804846, 17558.64882702339, 17911.3731019415, 18004.0, 1996.9301074462323, 2006.8059504594744, 2149.0866381787678, 2173.739151301838, 2185.519185779126, 167640.0, 169290.1535492327, 170022.0, 171579.0, 172492.0630952671, 11024.952629500185, 12411.937940310241, 12916.41876115577, 13048.673783155307, 13138.0, 15522.456714014956, 15560.92219538895, 16926.57683557921, 16933.0, 16939.153042347833, 7798.532088074734, 8970.551472426483, 9122.498262595489, 9233.492157931378, 9574.425534467815, 8508.829823403497, 8571.357148213909, 8651.91348086511, 8770.693025744069, 8886.955565222133, 6233.602686535849, 7977.242161994601, 7995.800104997422, 8023.83952320962, 8061.153578874134, 2160.211522794169, 2180.356794745554, 2180.5529866377356, 2184.2791878679996, 2366.9289921302043, 963.8843338799248, 980.3774603126923, 985.2905907746411, 1002.2633364477103, 1032.6024480574883, 20325.390238292875, 20871.686924696143, 20933.37199884006, 21292.935353232275, 21309.0, 970.9077637624414, 985.4776968206862, 1018.6791160784329, 1023.370627064365, 1028.4292217819032, 10631.043206111488, 10922.29005114665, 10941.28881622692, 10985.835212471819, 11063.889361106447, 522526.0, 526821.0, 536298.318508405, 561148.0, 561790.0, 124139.0, 126345.36827315805, 127683.3615831915, 128057.35971320084, 128509.35745321214, 1215.440897187286, 1217.6225370135287, 1253.141598005351, 1257.6478585995935, 1262.8926541244098, 16994.150292485574, 17579.472615821545, 17938.0, 17970.281188752302, 18192.81807181964, 53804.92390152253, 54653.0, 54827.17759233694, 56648.433515664314, 57319.42680573141, 58679.11981320369, 72675.0, 78579.10656700884, 79587.0, 80070.99822504484, 8722.171393717586, 9012.459252444874, 9057.818843623223, 9061.68284110053, 9086.772830679285, 13501.392437340539, 13717.519886803913, 14294.142351458946, 14459.204743739196, 15217.934744567774, 11611.361375124448, 11817.290962542274, 11962.76074478523, 12150.331731754839, 12190.390480476119, 11221.495032723384, 11325.207235493766, 11479.655610331703, 11544.820214673264, 11625.476853541442, 11899.286042837457, 12149.02807775358, 12269.75460490803, 12286.0, 12291.385430728607, 12760.170588911691, 12895.355232238539, 12923.15999460032, 13050.477980880658, 13193.20840749558, 11812.173147879566, 11856.288622682667, 11928.821067684165, 12076.0, 12100.152989291017], "value": 10496.313081627926, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1240.5099985505724, 1477.8596033376832, 1479.5857159995205, 1486.7472529669958, 1490.3889405343812, 11495.42522873856, 11854.703632409193, 11988.640340789769, 12082.939585302083, 12105.394730263484, 9979.401235925832, 11729.003034742036, 11843.46704398302, 11941.104417168726, 11966.40167991599, 2559.34736642156, 2597.4025974025985, 2611.4515951650083, 2611.8171727979047, 2612.1379944618325, 2520.521100990815, 2578.2523068310165, 2583.0442736187542, 2585.9224223273236, 2589.4562141950214, 2569.0366112707734, 2579.239124458289, 2598.44133511295, 2611.0, 2615.659964204651, 2525.7979361651023, 2549.2989427907287, 2572.957952029431, 2577.80666450017, 2578.9871050644815, 2463.507298540299, 2551.1836212412036, 2560.1807421625085, 2567.691876974775, 2571.6785401824773, 11565.0, 11815.232009919328, 11903.988161006326, 11916.34460104694, 11920.642380728592, 5690.9146362804595, 6849.972504124371, 6850.4862135340045, 6859.211190713082, 6860.348266914635, 3908.804559772, 4014.3376342903384, 4316.374125751749, 4344.63070638996, 4396.098799746044, 1065.5631191211567, 1210.9343777475854, 1252.993735031328, 1261.0920137500984, 1266.3351740336304, 14914.62713432162, 15340.999999999889, 16508.25712842913, 16559.751603725952, 16679.999200047914, 4737.763111844463, 5075.086484432763, 5554.500094991389, 5775.5668324875805, 5787.218725472091, 11650.951414372747, 11867.940660296643, 11872.76254474923, 11876.88123118775, 11887.88112118885, 6002.219711437572, 6544.607323560602, 6589.901151482732, 6606.636634985122, 6618.8345291368105, 5851.239338885917, 6016.669083200466, 6066.090086487062, 6201.255849298113, 6274.341194174637, 3976.9858686034877, 4272.508661503967, 4289.828406863753, 4410.867673969786, 4420.093880754438, 1077.9784404312027, 1286.2989670629343, 1286.530416398008, 1298.1886321049344, 1303.5372442782866, 642.4025656139835, 652.7454292825706, 752.6688257166829, 772.5789444752623, 800.2837460472961, 5740.827775166752, 6161.170552539135, 6253.155823963798, 6356.618602883842, 6424.421802037839, 597.4443767296456, 598.2223109957039, 603.3604379357895, 610.8472881779555, 611.3977731934042, 3842.884713458601, 4318.676099292566, 4419.955800442019, 4433.334999749999, 4552.726836389827, 11628.244164129304, 14657.0, 15095.924520377328, 15110.54668359951, 15165.772513412308, 14326.856731432761, 14891.032082914577, 15071.698566028617, 15077.321520531603, 15084.924575377054, 8389.916100839035, 8442.451240669337, 8590.140985901486, 8599.61301741423, 8603.225709686034, 6103.633781973096, 7158.6420678964805, 7181.605011724405, 7283.890741638986, 7307.926920730724, 11006.944965275123, 12630.989520838495, 12795.93602031984, 12866.228026318451, 12915.547955821501, 11471.885281147415, 13702.109362891779, 13724.794128087888, 13725.45098196061, 13771.724565508834, 9172.44965302084, 9258.074192580689, 9389.48357840298, 9418.764530886783, 9435.952820235854, 10599.258051936291, 10651.201159912882, 10731.839022414826, 10825.296355736851, 10900.890991089987, 9078.273738101068, 9128.726138215865, 9144.954275228582, 9167.954160229156, 9349.626014959325, 9036.0, 9074.31942604294, 9084.863727043967, 9085.90914090877, 9160.816783664422, 8203.671853125808, 8338.166183381612, 8346.582670866554, 8354.164583541597, 8355.791105222417, 6028.517718582415, 6068.575199736153, 6144.539159562962, 6192.2569291683485, 6238.563300568917, 6170.9074363885475, 6195.2255968004, 6265.404786545271, 6292.685365731787, 6365.681715914278], "value": 5307.548878028539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.28954657749614, 390.2156665103143, 395.38912380372324, 399.57844474079855, 401.9658329042031, 6108.358622344656, 6131.601445906013, 6141.447269745722, 6156.5690401671845, 6300.928842096843, 5282.652923504501, 5978.774351257993, 6143.784967526138, 6153.969230153843, 6155.8768824623485, 3652.579953305364, 4257.8296868125235, 4390.165868484994, 4448.555144485558, 4467.731936083837, 3425.7088147507493, 4398.824047038114, 4421.182081314967, 4450.9777451112695, 4455.732656040632, 3941.0344465605967, 4143.668506519486, 4402.669799765014, 4416.734995900256, 4423.181711383389, 3778.882422001832, 4355.194289056519, 4360.585744354281, 4412.294032954744, 4422.469303683562, 3128.2335827722127, 3760.6615404613717, 3769.3968964965475, 3773.867914622975, 3776.206996530732, 5282.577393808486, 5330.733463326818, 5871.236739223874, 5891.175235467038, 5902.586818922677, 4212.7472351659, 5225.451327610622, 5314.176302673053, 5314.202869569557, 5375.892482150335, 5661.886762264731, 6257.7184026718905, 6552.082708420778, 6578.210614726216, 6585.473162147065, 4326.069894972565, 4328.329108988112, 4329.502107257674, 4337.306031035019, 4357.847475338348, 5774.335951365605, 6722.596644201363, 6920.688569014406, 6971.0, 6984.476164287648, 3688.9710659137368, 3874.5737968823405, 4116.547179810218, 4281.571842815693, 4346.00041990344, 4421.336799480104, 4423.270160423558, 4430.534793846664, 4439.001224724476, 4448.266036104006, 4588.0, 4968.205087186033, 5179.19722443022, 5256.526912577886, 5258.474152584788, 5079.288899554065, 5743.253377061039, 6530.575512591667, 6555.704993275314, 6565.835854103686, 1491.403438624559, 1497.977530337046, 1505.3075585230918, 1507.2915729607066, 1509.2529198046818, 983.9163671088038, 986.6201512417771, 1050.2543194331956, 1187.3706935324292, 1211.54567037361, 5504.862378440571, 5745.569082318842, 6234.657093859882, 6262.373762623702, 6266.373362663697, 5644.350899646511, 5739.8852022960145, 6738.831529211711, 6817.931820681829, 6858.828529286708, 4630.4443466784205, 5091.8981620367385, 5420.83737487876, 6183.81448556535, 6257.374262573706, 3191.5744034533923, 3502.5972013218807, 3503.3518799022263, 3563.8396272167297, 3583.8999265635557, 5482.643628164156, 6662.300458451793, 6724.93275067243, 6988.527342500804, 7030.718771249195, 6990.196127445409, 6999.440044796404, 7001.334873187039, 7005.614691191931, 7028.051213086168, 6858.588484690935, 6887.345702158287, 6921.342472465007, 6985.476089293222, 7030.191527974246, 5931.584789064724, 6156.460035479707, 6776.442367756967, 6841.315868413119, 6850.725970961105, 5829.0, 5888.411158884249, 6612.50406219545, 6644.335566443317, 6738.630684657671, 6574.76988305407, 6895.4138898192205, 7092.929070709433, 7114.324139206767, 7154.856902862018, 4398.318260669601, 4403.867883963486, 4419.911601768011, 4427.269500532376, 4432.778361081869, 6007.789727359696, 6589.143411356493, 6846.554973926679, 6886.827829304107, 6945.340192681687, 5969.582129250911, 6400.9039864403, 6647.534672572873, 6679.432248258758, 6705.195376554844, 5937.7921772737745, 6268.558231606665, 6346.492280617632, 6472.67310201417, 6558.442532384801, 5873.559483038704, 5991.520678345809, 6472.838179045373, 6498.122753428227, 6508.60948343101, 6365.490760739222, 6604.7027883746305, 6642.236142843635, 6647.933520664731, 6686.465082793462, 5091.389033316026, 6391.456726178327, 6493.188351456068, 6494.545381823416, 6494.856697289789], "value": 4710.451522453808, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.817211611039, 828.9088200297963, 859.8366310401025, 860.6487814131812, 863.7797361672773, 14466.45571834902, 15088.094714317149, 16431.835681643195, 16728.41450549231, 16870.662586748258, 12629.81055284169, 13188.142770719896, 17500.037497937607, 17534.912325438356, 17560.648787024253, 948.7485816258697, 965.1024547171128, 967.690339091491, 971.0386717150042, 971.2278738402987, 938.7277689470079, 962.5091203486237, 975.8975307592707, 977.9266555008368, 979.3487330924927, 995.412706503163, 1018.9962886556735, 1030.340582027503, 1037.092544023979, 1039.3504059962524, 926.5645146781002, 1019.6176433837311, 1027.6300531808542, 1031.690492852145, 1033.4212840809096, 758.9696412143508, 759.6771372166806, 760.5740785160273, 763.1605234242345, 765.0933643632313, 16458.506244812674, 16818.495445136665, 16979.23593438298, 17318.480445586654, 17588.91205543964, 17248.310067597406, 17273.0, 17529.912350438168, 17626.383076592258, 17774.28902843885, 14379.856201437957, 14425.927870360581, 14470.27648617565, 14841.851581484156, 14967.476138335214, 3658.1037645776955, 3668.1746607013474, 3671.0822294426393, 3673.430618254175, 3684.078980254936, 96804.0, 118271.81728182694, 120023.19965200529, 120471.39764301122, 121552.0, 13008.154469959421, 13344.866551334557, 13402.128861624158, 13472.932635336956, 13515.459381624625, 25132.497350052898, 25365.87317063403, 25629.3592660185, 25890.0, 26078.0, 7758.418118641124, 7782.610869456505, 7800.336971357385, 7831.804204894809, 7847.293743563107, 3311.9503207451908, 3848.5574158971517, 3883.5922228165637, 3982.9601703983167, 3990.521137463465, 4290.184864875664, 4368.497622773358, 4378.190034843564, 4379.4525684289465, 4394.758288294111, 924.3437159616612, 936.9953150234205, 940.6002448959165, 946.3895787217207, 950.1970834644715, 645.773979107318, 654.9279579246279, 655.3872129558878, 663.3234101216732, 669.6283562622791, 10104.898951010542, 11162.441877906072, 11785.175037747274, 12011.819822702666, 12039.337836418907, 579.9246098007317, 618.3445547719432, 627.1533429869639, 638.4732595608641, 686.1491750229779, 4545.681802273875, 5273.789048438096, 5321.3880403754065, 5480.725963701799, 5540.168974653834, 71477.0, 95411.04588954021, 95654.0, 96037.5198124019, 96891.0, 64123.358766412675, 78642.0, 78969.0, 79104.20895791083, 80836.59581702054, 73174.63412682903, 74616.62691686615, 74803.62598186974, 75635.62182189054, 76769.61615192, 10599.046085852311, 11061.225714199929, 11158.27471214401, 11344.092472602337, 11481.024112950492, 24653.0, 38039.66861159846, 38409.00682911203, 38753.0, 39093.80453097716, 49296.50703492919, 49935.0, 50238.74880625573, 50699.493005069475, 50907.490925091755, 14050.297485125908, 14143.43426262938, 14371.928140359649, 14583.343749531085, 14637.487687930872, 13383.92928565693, 14374.568762937128, 14406.855931440552, 15664.451744188898, 15878.364865405254, 14152.0, 14342.282885855457, 14789.630259243606, 14828.110313381232, 14834.332455039765, 14411.78382324245, 14565.27173641335, 14652.926735366254, 14657.267136643339, 14687.70624587481, 12874.806877896652, 13805.585832425044, 14265.786013210018, 14335.78496322577, 14410.351534181196, 8732.738017859474, 9966.252531060054, 10480.161587072864, 10543.0, 10847.294925829796, 10434.791304173723, 11346.886531134913, 11573.826392604286, 11661.125415594033, 12023.398830058288], "value": 7913.245581163895, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1502.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 116.0, 115.0, 114.0, 115.0, 115.0, 114.0, 115.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 121.0, 156.0, 113.0, 112.0, 112.0, 114.0, 140.0, 110.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 203.0, 195.0, 200.0, 227.0, 261.0, 235.0, 244.0, 265.0, 203.0], "value": 223.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 61.0, 79.0, 89.0, 59.0, 59.0, 57.0, 57.0, 80.0, 57.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 82.0, 73.0, 68.0, 68.0, 87.0, 72.0, 69.0, 91.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 92.0, 90.0, 116.0, 90.0, 91.0, 92.0, 92.0, 91.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 97.0, 98.0, 148.0, 98.0, 99.0, 98.0, 97.0, 99.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 92.0, 90.0, 100.0, 90.0, 91.0, 92.0, 91.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 52.0, 50.0, 50.0, 52.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 62.0, 60.0, 63.0, 60.0, 56.0, 59.0, 57.0, 56.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 81.0, 82.0, 84.0, 82.0, 87.0, 84.0, 83.0, 81.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 150.0, 137.0, 137.0, 138.0, 138.0, 155.0, 176.0, 173.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 235.0, 183.0, 187.0, 182.0, 195.0, 204.0, 182.0, 185.0, 211.0], "value": 198.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 66.0, 70.0, 66.0, 70.0, 67.0, 68.0, 67.0, 70.0], "value": 68.6, "unit": "score"}]}]} 16:53:24 INFO - Return code: 0 16:53:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:53:24 INFO - # TBPL SUCCESS # 16:53:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:53:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:53:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:53:24 INFO - 2017-05-17 16:53:24.476 screenresolution[1665:10788] starting screenresolution argv=screenresolution get 16:53:24 INFO - 2017-05-17 16:53:24.479 screenresolution[1665:10788] Display 0: 1600x1200x32@60 16:53:24 INFO - 2017-05-17 16:53:24.484 screenresolution[1666:10791] starting screenresolution argv=screenresolution list 16:53:24 INFO - Available Modes on Display 0 16:53:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:53:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:53:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:53:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:53:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:53:24 INFO - Intel Iris: 16:53:24 INFO - Chipset Model: Intel Iris 16:53:24 INFO - Type: GPU 16:53:24 INFO - Bus: Built-In 16:53:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:53:24 INFO - Vendor: Intel (0x8086) 16:53:24 INFO - Device ID: 0x0a2e 16:53:24 INFO - Revision ID: 0x0009 16:53:24 INFO - Displays: 16:53:24 INFO - Display: 16:53:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:53:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:53:24 INFO - Display Serial Number: 1600x1200 60 16:53:24 INFO - Main Display: Yes 16:53:24 INFO - Mirror: Off 16:53:24 INFO - Online: Yes 16:53:24 INFO - Rotation: Supported 16:53:24 INFO - Return code: 0 16:53:24 INFO - Running post-action listener: _resource_record_post_action 16:53:24 INFO - [mozharness: 2017-05-17 23:53:24.706059Z] Finished run-tests step (success) 16:53:24 INFO - Running post-run listener: _resource_record_post_run 16:53:24 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 41208832; Write bytes: 531125760; Read time: 408; Write time: 1774 16:53:24 INFO - TinderboxPrint: CPU usage
54.1% 16:53:24 INFO - TinderboxPrint: I/O read bytes / time
41,208,832 / 408 16:53:24 INFO - TinderboxPrint: I/O write bytes / time
531,125,760 / 1,774 16:53:24 INFO - TinderboxPrint: CPU idle
2,193.3 (45.9%) 16:53:24 INFO - TinderboxPrint: CPU system
955.9 (20.0%) 16:53:24 INFO - TinderboxPrint: CPU user
1,624.5 (34.0%) 16:53:24 INFO - TinderboxPrint: Swap in / out
255,381,504 / 0 16:53:24 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 215986688; Write bytes: 210753024; Read time: 16904; Write time: 428 16:53:24 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 36445184; Write bytes: 312393728; Read time: 374; Write time: 1317 16:53:25 INFO - Running post-run listener: _upload_blobber_files 16:53:25 INFO - Blob upload gear active. 16:53:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:53:25 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 16:53:25 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'] 16:53:25 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 16:53:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:53:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:53:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:53:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:26 INFO - (blobuploader) - INFO - Done attempting. 16:53:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:53:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:53:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:27 INFO - (blobuploader) - INFO - Done attempting. 16:53:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:53:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:53:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:28 INFO - (blobuploader) - INFO - Done attempting. 16:53:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:53:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:53:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:53:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:53:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:53:33 INFO - (blobuploader) - INFO - Done attempting. 16:53:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:53:33 INFO - Return code: 0 16:53:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:53:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:53:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ac809010ef33f92671c9a9cedaceb395085533355d4dd19687f9a12a950470cca96ec68cb41fb71515ca3b4b057779ce0ab470bdacab1324438dab4fb426446", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f756d51ee943e16cee65bc857c5589825339ba866747ba2a5f054e4bd2ff2f877010d77d6758f15b2ce16441cead10c018c91419c866b0abe84dae934ea97afa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6749b4818f3f6b6731c8c93074ee2fba9d8a0009bde38a0294cfa3b6f0ab859f086fd16a354f94fafcf798eddf645128550eb61f290115623ec745a6e74d16f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1febf1b171a9a0e34923bfc3988e27079ec101d924243e68d5da22b605557987ddf135534ff5d268dbee7d03597f6862d1cf1a87e55c3ed5507fefac06396d2d"} 16:53:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:53:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:53:33 INFO - Contents: 16:53:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ac809010ef33f92671c9a9cedaceb395085533355d4dd19687f9a12a950470cca96ec68cb41fb71515ca3b4b057779ce0ab470bdacab1324438dab4fb426446", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f756d51ee943e16cee65bc857c5589825339ba866747ba2a5f054e4bd2ff2f877010d77d6758f15b2ce16441cead10c018c91419c866b0abe84dae934ea97afa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6749b4818f3f6b6731c8c93074ee2fba9d8a0009bde38a0294cfa3b6f0ab859f086fd16a354f94fafcf798eddf645128550eb61f290115623ec745a6e74d16f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1febf1b171a9a0e34923bfc3988e27079ec101d924243e68d5da22b605557987ddf135534ff5d268dbee7d03597f6862d1cf1a87e55c3ed5507fefac06396d2d"} 16:53:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:53:33 INFO - Copying logs to upload dir... 16:53:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:53:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.979617 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-17 16:53:33.917294) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 16:53:33.924389) ========= 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.EWVz1PctLp/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.siZVzDuNeG/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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/0ac809010ef33f92671c9a9cedaceb395085533355d4dd19687f9a12a950470cca96ec68cb41fb71515ca3b4b057779ce0ab470bdacab1324438dab4fb426446", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f756d51ee943e16cee65bc857c5589825339ba866747ba2a5f054e4bd2ff2f877010d77d6758f15b2ce16441cead10c018c91419c866b0abe84dae934ea97afa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6749b4818f3f6b6731c8c93074ee2fba9d8a0009bde38a0294cfa3b6f0ab859f086fd16a354f94fafcf798eddf645128550eb61f290115623ec745a6e74d16f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1febf1b171a9a0e34923bfc3988e27079ec101d924243e68d5da22b605557987ddf135534ff5d268dbee7d03597f6862d1cf1a87e55c3ed5507fefac06396d2d"} build_url:https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010852 build_url: 'https://queue.taskcluster.net/v1/task/P8rk4hefQGmh-4MNfUFPNw/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/0ac809010ef33f92671c9a9cedaceb395085533355d4dd19687f9a12a950470cca96ec68cb41fb71515ca3b4b057779ce0ab470bdacab1324438dab4fb426446", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f756d51ee943e16cee65bc857c5589825339ba866747ba2a5f054e4bd2ff2f877010d77d6758f15b2ce16441cead10c018c91419c866b0abe84dae934ea97afa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6749b4818f3f6b6731c8c93074ee2fba9d8a0009bde38a0294cfa3b6f0ab859f086fd16a354f94fafcf798eddf645128550eb61f290115623ec745a6e74d16f1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1febf1b171a9a0e34923bfc3988e27079ec101d924243e68d5da22b605557987ddf135534ff5d268dbee7d03597f6862d1cf1a87e55c3ed5507fefac06396d2d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 16:53:33.962712) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 16:53:33.963157) ========= 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.EWVz1PctLp/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.siZVzDuNeG/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.005179 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 16:53:34.013961) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 16:53:34.014352) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 16:53:34.014757) ========= ========= Total master_lag: 0.19 =========