builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0314 starttime: 1495769354.83 results: success (0) revision: 0874cf4bb194d381a3afaa51276b6cee22f82211 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.834350) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.834953) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.835266) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.858803) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.859078) ========= 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.GY4UC5oWFu/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.RmverSR5Md/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-25 20:29:14-- 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% 55.6M=0s 2017-05-25 20:29:14 (55.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102818 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.983930) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:14.984240) ========= 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.GY4UC5oWFu/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.RmverSR5Md/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.021651 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:15.032948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 20:29:15.033253) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0874cf4bb194d381a3afaa51276b6cee22f82211 --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 0874cf4bb194d381a3afaa51276b6cee22f82211 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GY4UC5oWFu/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.RmverSR5Md/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-25 20:29:15,114 truncating revision to first 12 chars 2017-05-25 20:29:15,114 Setting DEBUG logging. 2017-05-25 20:29:15,114 attempt 1/10 2017-05-25 20:29:15,114 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0874cf4bb194?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 20:29:15,938 unpacking tar archive at: mozilla-central-0874cf4bb194/testing/mozharness/ program finished with exit code 0 elapsedTime=1.099488 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 20:29:16.154162) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:16.154490) ========= script_repo_revision: 0874cf4bb194d381a3afaa51276b6cee22f82211 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:16.154855) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:16.155121) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 20:29:16.173823) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-25 20:29:16.174154) ========= /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.GY4UC5oWFu/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.RmverSR5Md/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:29:16 INFO - MultiFileLogger online at 20170525 20:29:16 in /builds/slave/test 20:29:16 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 20:29:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:29:16 INFO - {'append_to_log': False, 20:29:16 INFO - 'base_work_dir': '/builds/slave/test', 20:29:16 INFO - 'blob_upload_branch': 'Firefox', 20:29:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:29:16 INFO - 'branch': 'Firefox', 20:29:16 INFO - 'buildbot_json_path': 'buildprops.json', 20:29:16 INFO - 'config_files': ('talos/mac_config.py',), 20:29:16 INFO - 'default_actions': ('clobber', 20:29:16 INFO - 'read-buildbot-config', 20:29:16 INFO - 'download-and-extract', 20:29:16 INFO - 'populate-webroot', 20:29:16 INFO - 'create-virtualenv', 20:29:16 INFO - 'install', 20:29:16 INFO - 'run-tests'), 20:29:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:29:16 INFO - 'download_minidump_stackwalk': True, 20:29:16 INFO - 'download_symbols': 'ondemand', 20:29:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:29:16 INFO - 'tooltool.py': '/tools/tooltool.py', 20:29:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:29:16 INFO - '/tools/misc-python/virtualenv.py')}, 20:29:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:29:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:29:16 INFO - 'gecko_profile': False, 20:29:16 INFO - 'gecko_profile_interval': 0, 20:29:16 INFO - 'installer_path': 'installer.exe', 20:29:16 INFO - 'log_level': 'info', 20:29:16 INFO - 'log_name': 'talos', 20:29:16 INFO - 'log_to_console': True, 20:29:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:29:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:29:16 INFO - 'opt_config_files': (), 20:29:16 INFO - 'pip_index': False, 20:29:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:29:16 INFO - 'cmd': ('bash', 20:29:16 INFO - '-c', 20:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:29:16 INFO - 'enabled': True, 20:29:16 INFO - 'halt_on_failure': False, 20:29:16 INFO - 'name': 'check_screen_resolution'},), 20:29:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:29:16 INFO - 'cmd': ('bash', 20:29:16 INFO - '-c', 20:29:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:29:16 INFO - 'enabled': True, 20:29:16 INFO - 'halt_on_failure': False, 20:29:16 INFO - 'name': 'check_screen_resolution'},), 20:29:16 INFO - 'run_cmd_checks_enabled': True, 20:29:16 INFO - 'suite': 'dromaeojs-e10s', 20:29:16 INFO - 'system_bits': '32', 20:29:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:29:16 INFO - 'title': 't-yosemite-r7-0314', 20:29:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:29:16 INFO - 'use_talos_json': True, 20:29:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:29:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:29:16 INFO - 'work_dir': 'build'} 20:29:16 INFO - [mozharness: 2017-05-26 03:29:16.372642Z] Running clobber step. 20:29:16 INFO - Running pre-action listener: _resource_record_pre_action 20:29:16 INFO - Running main action method: clobber 20:29:16 INFO - rmtree: /builds/slave/test/build 20:29:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:29:18 INFO - Running post-action listener: _resource_record_post_action 20:29:18 INFO - [mozharness: 2017-05-26 03:29:18.849020Z] Finished clobber step (success) 20:29:18 INFO - [mozharness: 2017-05-26 03:29:18.849212Z] Running read-buildbot-config step. 20:29:18 INFO - Running pre-action listener: _resource_record_pre_action 20:29:18 INFO - Running main action method: read_buildbot_config 20:29:18 INFO - Using buildbot properties: 20:29:18 INFO - { 20:29:18 INFO - "project": "", 20:29:18 INFO - "product": "firefox", 20:29:18 INFO - "who": "ryanvm@gmail.com", 20:29:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 20:29:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:29:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:29:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:29:18 INFO - "stage_platform": "macosx64", 20:29:18 INFO - "basedir": "/builds/slave/test", 20:29:18 INFO - "buildnumber": 86, 20:29:18 INFO - "platform": "yosemite_r7", 20:29:18 INFO - "parent_task_id": "BZla1udOSeSHNeV7xcTmCw", 20:29:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:29:18 INFO - "slavebuilddir": "test", 20:29:18 INFO - "taskId": "dMdlYNdlQ5ylp_c9jj0kjQ", 20:29:18 INFO - "branch": "mozilla-central", 20:29:18 INFO - "script_repo_revision": "production", 20:29:18 INFO - "revision": "0874cf4bb194d381a3afaa51276b6cee22f82211", 20:29:18 INFO - "slavename": "t-yosemite-r7-0314", 20:29:18 INFO - "repo_path": "mozilla-central" 20:29:18 INFO - } 20:29:18 INFO - Finding installer, test and symbols from parent task. 20:29:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMdlYNdlQ5ylp_c9jj0kjQ'}, attempt #1 20:29:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMdlYNdlQ5ylp_c9jj0kjQ'}, attempt #1 20:29:19 INFO - Task dependencies: M8khRLlHSPOoV1vQ-ftUug BZla1udOSeSHNeV7xcTmCw 20:29:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw'}, attempt #1 20:29:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:29:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/test_packages.json 20:29:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/target.crashreporter-symbols.zip 20:29:20 INFO - Running post-action listener: _resource_record_post_action 20:29:20 INFO - [mozharness: 2017-05-26 03:29:20.213339Z] Finished read-buildbot-config step (success) 20:29:20 INFO - [mozharness: 2017-05-26 03:29:20.213450Z] Running download-and-extract step. 20:29:20 INFO - Running pre-action listener: _resource_record_pre_action 20:29:20 INFO - Running main action method: download_and_extract 20:29:20 INFO - mkdir: /builds/slave/test/build/tests 20:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:20 INFO - trying https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 20:29:20 INFO - Downloading https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/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 20:29:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/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 20:29:21 INFO - Downloaded 1325 bytes. 20:29:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:29:21 INFO - Using the following test package requirements: 20:29:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 20:29:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:29:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 20:29:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 20:29:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'jsshell-mac.zip'], 20:29:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 20:29:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:29:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 20:29:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 20:29:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 20:29:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:29:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 20:29:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:29:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:29:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:29:21 INFO - Fetch https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:29:22 INFO - Content-Length response header: 57630916 20:29:22 INFO - Bytes received: 57630916 20:29:40 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 20:29:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:29:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:29:40 INFO - Fetch https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:29:42 INFO - Content-Length response header: 57630916 20:29:42 INFO - Bytes received: 57630916 20:30:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 20:30:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:30:00 INFO - Fetch https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 20:30:01 INFO - Content-Length response header: 14411888 20:30:01 INFO - Bytes received: 14411888 20:30: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')]} 20:30:02 INFO - trying https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:30:02 INFO - Downloading https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 20:30:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/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 20:30:04 INFO - Downloaded 67229522 bytes. 20:30:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:30:04 INFO - mkdir: /builds/slave/test/properties 20:30:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:30:04 INFO - Writing to file /builds/slave/test/properties/build_url 20:30:04 INFO - Contents: 20:30:04 INFO - build_url:https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:30:04 INFO - Running post-action listener: _resource_record_post_action 20:30:04 INFO - Running post-action listener: set_extra_try_arguments 20:30:04 INFO - [mozharness: 2017-05-26 03:30:04.323717Z] Finished download-and-extract step (success) 20:30:04 INFO - [mozharness: 2017-05-26 03:30:04.323870Z] Running populate-webroot step. 20:30:04 INFO - Running pre-action listener: _resource_record_pre_action 20:30:04 INFO - Running main action method: populate_webroot 20:30:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:30:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:30:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:30:04 INFO - '--tppagecycles', 20:30:04 INFO - '7'], 20:30:04 INFO - 'tests': ['tsvgm']}}, 20:30:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:30:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:30:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:30:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:30:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['damp', 'tps']}, 20:30:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'tests': ['damp', 'tps']}, 20:30:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['dromaeo_dom']}, 20:30:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:30:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:30:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:30:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:30:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['a11yr', 20:30:04 INFO - 'ts_paint', 20:30:04 INFO - 'tpaint', 20:30:04 INFO - 'sessionrestore', 20:30:04 INFO - 'sessionrestore_no_auto_restore', 20:30:04 INFO - 'tabpaint']}, 20:30:04 INFO - 'other-e10s': {'tests': ['a11yr', 20:30:04 INFO - 'ts_paint', 20:30:04 INFO - 'tpaint', 20:30:04 INFO - 'sessionrestore', 20:30:04 INFO - 'sessionrestore_no_auto_restore', 20:30:04 INFO - 'tabpaint']}, 20:30:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:30:04 INFO - 'ts_paint', 20:30:04 INFO - 'tpaint', 20:30:04 INFO - 'sessionrestore', 20:30:04 INFO - 'sessionrestore_no_auto_restore', 20:30:04 INFO - 'tabpaint']}, 20:30:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:30:04 INFO - 'ts_paint', 20:30:04 INFO - 'tpaint', 20:30:04 INFO - 'sessionrestore', 20:30:04 INFO - 'sessionrestore_no_auto_restore', 20:30:04 INFO - 'tabpaint']}, 20:30:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['a11yr', 20:30:04 INFO - 'ts_paint', 20:30:04 INFO - 'tpaint', 20:30:04 INFO - 'sessionrestore', 20:30:04 INFO - 'sessionrestore_no_auto_restore', 20:30:04 INFO - 'tabpaint']}, 20:30:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['a11yr', 20:30:04 INFO - 'ts_paint', 20:30:04 INFO - 'tpaint', 20:30:04 INFO - 'sessionrestore', 20:30:04 INFO - 'sessionrestore_no_auto_restore', 20:30:04 INFO - 'tabpaint']}, 20:30:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 20:30:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 20:30:04 INFO - 'bloom_basic_ref']}, 20:30:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['tsvgx', 20:30:04 INFO - 'tsvgr_opacity', 20:30:04 INFO - 'tart', 20:30:04 INFO - 'tscrollx', 20:30:04 INFO - 'cart', 20:30:04 INFO - 'tsvg_static']}, 20:30:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:30:04 INFO - 'tsvgr_opacity', 20:30:04 INFO - 'tart', 20:30:04 INFO - 'tscrollx', 20:30:04 INFO - 'cart', 20:30:04 INFO - 'tsvg_static']}, 20:30:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'talos_options': ['--disable-e10s'], 20:30:04 INFO - 'tests': ['tp5o']}, 20:30:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:30:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'talos_options': ['--disable-e10s', 20:30:04 INFO - '--xperf_path', 20:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:04 INFO - 'tests': ['tp5n']}, 20:30:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:30:04 INFO - 'talos_options': ['--xperf_path', 20:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:04 INFO - 'tests': ['tp5n']}}, 20:30:04 INFO - 'talos.zip': {'path': '', 20:30:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:30:04 INFO - Running post-action listener: _resource_record_post_action 20:30:04 INFO - [mozharness: 2017-05-26 03:30:04.344889Z] Finished populate-webroot step (success) 20:30:04 INFO - [mozharness: 2017-05-26 03:30:04.345017Z] Running create-virtualenv step. 20:30:04 INFO - Running pre-action listener: _resource_record_pre_action 20:30:04 INFO - Running main action method: create_virtualenv 20:30:04 INFO - Creating virtualenv /builds/slave/test/build/venv 20:30: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 20:30:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:30:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:30:04 INFO - Using real prefix '/tools/python27' 20:30:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:30:05 INFO - Installing distribute.............................................................................................................................................................................................done. 20:30:08 INFO - Installing pip.................done. 20:30:08 INFO - Return code: 0 20:30:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:30:08 INFO - Reading from file tmpfile_stdout 20:30:08 INFO - Output received: 20:30: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) 20:30:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:30: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')]} 20:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30: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')]} 20:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30: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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30: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 20:30: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 20:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:08 INFO - 'HOME': '/Users/cltbld', 20:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:08 INFO - 'LOGNAME': 'cltbld', 20:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:08 INFO - 'MOZ_NO_REMOTE': '1', 20:30:08 INFO - 'NO_EM_RESTART': '1', 20:30:08 INFO - 'PAGER': '/bin/cat', 20:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:08 INFO - 'PWD': '/builds/slave/test', 20:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:08 INFO - 'SHELL': '/bin/bash', 20:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:08 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:08 INFO - 'USER': 'cltbld', 20:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:08 INFO - 'XPC_FLAGS': '0x0', 20:30:08 INFO - 'XPC_SERVICE_NAME': '0', 20:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30: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 20:30:08 INFO - Cleaning up... 20:30:08 INFO - Return code: 0 20:30:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:30: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')]} 20:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30: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')]} 20:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30: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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30: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 20:30: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 20:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:08 INFO - 'HOME': '/Users/cltbld', 20:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:08 INFO - 'LOGNAME': 'cltbld', 20:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:08 INFO - 'MOZ_NO_REMOTE': '1', 20:30:08 INFO - 'NO_EM_RESTART': '1', 20:30:08 INFO - 'PAGER': '/bin/cat', 20:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:08 INFO - 'PWD': '/builds/slave/test', 20:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:08 INFO - 'SHELL': '/bin/bash', 20:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:08 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:08 INFO - 'USER': 'cltbld', 20:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:08 INFO - 'XPC_FLAGS': '0x0', 20:30:08 INFO - 'XPC_SERVICE_NAME': '0', 20:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:08 INFO - Downloading/unpacking psutil>=3.1.1 20:30:08 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 20:30:08 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 20:30:08 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 20:30:08 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 20:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:30:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:10 INFO - Installing collected packages: psutil 20:30:10 INFO - Running setup.py install for psutil 20:30:10 INFO - building 'psutil._psutil_osx' extension 20:30:10 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 20:30: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 20:30: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 20:30:11 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 20:30:11 INFO - building 'psutil._psutil_posix' extension 20:30:11 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 20:30:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:30:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:30:11 INFO - ^ 20:30:11 INFO - 1 warning generated. 20:30:11 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 20:30:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:11 INFO - Successfully installed psutil 20:30:11 INFO - Cleaning up... 20:30:11 INFO - Return code: 0 20:30:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:30:11 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')]} 20:30:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:11 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')]} 20:30:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:12 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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30:12 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 20:30:12 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 20:30:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:12 INFO - 'HOME': '/Users/cltbld', 20:30:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:12 INFO - 'LOGNAME': 'cltbld', 20:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:12 INFO - 'MOZ_NO_REMOTE': '1', 20:30:12 INFO - 'NO_EM_RESTART': '1', 20:30:12 INFO - 'PAGER': '/bin/cat', 20:30:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:12 INFO - 'PWD': '/builds/slave/test', 20:30:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:12 INFO - 'SHELL': '/bin/bash', 20:30:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:12 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:12 INFO - 'USER': 'cltbld', 20:30:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:12 INFO - 'XPC_FLAGS': '0x0', 20:30:12 INFO - 'XPC_SERVICE_NAME': '0', 20:30:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:30:12 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 20:30:12 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 20:30:12 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 20:30:12 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 20:30:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:30:14 INFO - Installing collected packages: mozsystemmonitor 20:30:14 INFO - Running setup.py install for mozsystemmonitor 20:30:14 INFO - Successfully installed mozsystemmonitor 20:30:14 INFO - Cleaning up... 20:30:14 INFO - Return code: 0 20:30:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:30:14 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')]} 20:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:14 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')]} 20:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:14 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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30:14 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 20:30:14 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 20:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:14 INFO - 'HOME': '/Users/cltbld', 20:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:14 INFO - 'LOGNAME': 'cltbld', 20:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:14 INFO - 'MOZ_NO_REMOTE': '1', 20:30:14 INFO - 'NO_EM_RESTART': '1', 20:30:14 INFO - 'PAGER': '/bin/cat', 20:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:14 INFO - 'PWD': '/builds/slave/test', 20:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:14 INFO - 'SHELL': '/bin/bash', 20:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:14 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:14 INFO - 'USER': 'cltbld', 20:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:14 INFO - 'XPC_FLAGS': '0x0', 20:30:14 INFO - 'XPC_SERVICE_NAME': '0', 20:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:14 INFO - Downloading/unpacking jsonschema==2.5.1 20:30:14 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 20:30:14 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 20:30:14 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 20:30:14 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 20:30:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:30:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:30:16 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 20:30:16 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 20:30:16 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 20:30:16 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 20:30:16 INFO - Downloading functools32-3.2.3-2.tar.gz 20:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:30:16 INFO - warning: no files found matching '*.txt' 20:30:16 INFO - no previously-included directories found matching 'build' 20:30:16 INFO - no previously-included directories found matching 'dist' 20:30:16 INFO - no previously-included directories found matching '.git*' 20:30:16 INFO - Installing collected packages: jsonschema, functools32 20:30:16 INFO - Running setup.py install for functools32 20:30:16 INFO - warning: no files found matching '*.txt' 20:30:16 INFO - no previously-included directories found matching 'build' 20:30:16 INFO - no previously-included directories found matching 'dist' 20:30:16 INFO - no previously-included directories found matching '.git*' 20:30:16 INFO - Successfully installed jsonschema functools32 20:30:16 INFO - Cleaning up... 20:30:16 INFO - Return code: 0 20:30:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:30:16 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')]} 20:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:16 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')]} 20:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:16 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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30:16 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 20:30:16 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 20:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:16 INFO - 'HOME': '/Users/cltbld', 20:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:16 INFO - 'LOGNAME': 'cltbld', 20:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:16 INFO - 'MOZ_NO_REMOTE': '1', 20:30:16 INFO - 'NO_EM_RESTART': '1', 20:30:16 INFO - 'PAGER': '/bin/cat', 20:30:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:16 INFO - 'PWD': '/builds/slave/test', 20:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:16 INFO - 'SHELL': '/bin/bash', 20:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:16 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:16 INFO - 'USER': 'cltbld', 20:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:16 INFO - 'XPC_FLAGS': '0x0', 20:30:16 INFO - 'XPC_SERVICE_NAME': '0', 20:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:30:17 INFO - Cleaning up... 20:30:17 INFO - Return code: 0 20:30:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:30: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')]} 20:30:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30: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')]} 20:30:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30: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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30: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 20:30: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 20:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:17 INFO - 'HOME': '/Users/cltbld', 20:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:17 INFO - 'LOGNAME': 'cltbld', 20:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:17 INFO - 'MOZ_NO_REMOTE': '1', 20:30:17 INFO - 'NO_EM_RESTART': '1', 20:30:17 INFO - 'PAGER': '/bin/cat', 20:30:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:17 INFO - 'PWD': '/builds/slave/test', 20:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:17 INFO - 'SHELL': '/bin/bash', 20:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:17 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:17 INFO - 'USER': 'cltbld', 20:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:17 INFO - 'XPC_FLAGS': '0x0', 20:30:17 INFO - 'XPC_SERVICE_NAME': '0', 20:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:17 INFO - Downloading/unpacking blobuploader==1.2.4 20:30: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 20:30: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 20:30: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 20:30: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 20:30:18 INFO - Downloading blobuploader-1.2.4.tar.gz 20:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:30:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:30: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 20:30: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 20:30: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 20:30: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 20:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:30:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:30: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 20:30: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 20:30: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 20:30: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 20:30:19 INFO - Downloading docopt-0.6.1.tar.gz 20:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:30:19 INFO - Installing collected packages: blobuploader, requests, docopt 20:30:19 INFO - Running setup.py install for blobuploader 20:30:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:30:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:30:19 INFO - Running setup.py install for requests 20:30:20 INFO - Running setup.py install for docopt 20:30:20 INFO - Successfully installed blobuploader requests docopt 20:30:20 INFO - Cleaning up... 20:30:20 INFO - Return code: 0 20:30:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:20 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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30:20 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 20:30:20 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 20:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:20 INFO - 'HOME': '/Users/cltbld', 20:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:20 INFO - 'LOGNAME': 'cltbld', 20:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:20 INFO - 'MOZ_NO_REMOTE': '1', 20:30:20 INFO - 'NO_EM_RESTART': '1', 20:30:20 INFO - 'PAGER': '/bin/cat', 20:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:20 INFO - 'PWD': '/builds/slave/test', 20:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:20 INFO - 'SHELL': '/bin/bash', 20:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:20 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:20 INFO - 'USER': 'cltbld', 20:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:20 INFO - 'XPC_FLAGS': '0x0', 20:30:20 INFO - 'XPC_SERVICE_NAME': '0', 20:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:20 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-AaFYtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:20 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-OJrd0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:20 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-jEpiHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-jB40lH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Tofn4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-GAJTL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-8eFeJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-1ioYyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ldAP8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-rSq5GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-eyCyaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-YCpxMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:21 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Sa3JiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:22 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-OtKhr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:22 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-k6ePfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:22 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-jcPNJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:22 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-cIR90R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:30:22 INFO - Running setup.py install for manifestparser 20:30:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:30:22 INFO - Running setup.py install for mozcrash 20:30:22 INFO - Running setup.py install for mozdebug 20:30:22 INFO - Running setup.py install for mozdevice 20:30:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Running setup.py install for mozfile 20:30:23 INFO - Running setup.py install for mozhttpd 20:30:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Running setup.py install for mozinfo 20:30:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Running setup.py install for mozInstall 20:30:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Running setup.py install for mozleak 20:30:23 INFO - Running setup.py install for mozlog 20:30:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:30:23 INFO - Running setup.py install for moznetwork 20:30:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:30:24 INFO - Running setup.py install for mozprocess 20:30:24 INFO - Running setup.py install for mozprofile 20:30:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:30:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:30:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:30:24 INFO - Running setup.py install for mozrunner 20:30:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:30:24 INFO - Running setup.py install for mozscreenshot 20:30:24 INFO - Running setup.py install for moztest 20:30:24 INFO - Running setup.py install for mozversion 20:30:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:30:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:30:25 INFO - Cleaning up... 20:30:25 INFO - Return code: 0 20:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30: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')]} 20:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30: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')]} 20:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30: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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30: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 20:30: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 20:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:25 INFO - 'HOME': '/Users/cltbld', 20:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:25 INFO - 'LOGNAME': 'cltbld', 20:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:25 INFO - 'MOZ_NO_REMOTE': '1', 20:30:25 INFO - 'NO_EM_RESTART': '1', 20:30:25 INFO - 'PAGER': '/bin/cat', 20:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:25 INFO - 'PWD': '/builds/slave/test', 20:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:25 INFO - 'SHELL': '/bin/bash', 20:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:25 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:25 INFO - 'USER': 'cltbld', 20:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:25 INFO - 'XPC_FLAGS': '0x0', 20:30:25 INFO - 'XPC_SERVICE_NAME': '0', 20:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:25 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Hub8TO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:25 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)) 20:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:25 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Ic81j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:25 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)) 20:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:25 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-kbwTRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:25 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)) 20:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:25 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-x0KYAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 20:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:25 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-VVsVAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:25 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)) 20:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:25 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-hn37b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30: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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-FbacUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30: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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-np2Ymu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-VXIs0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Whuz6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-S_pOWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-5be7gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-W8PJl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2DSJeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-HTMt4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:26 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)) 20:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:26 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-q2drfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30: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)) 20:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:27 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-tx1IHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:27 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)) 20:30:27 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)) 20:30:27 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)) 20:30:27 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)) 20:30:27 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:30:27 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:30:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:30:27 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 20:30:27 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 20:30:27 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 20:30:27 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 20:30:28 INFO - Downloading blessings-1.6.tar.gz 20:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:30:28 INFO - Installing collected packages: blessings 20:30:28 INFO - Running setup.py install for blessings 20:30:28 INFO - Successfully installed blessings 20:30:28 INFO - Cleaning up... 20:30:28 INFO - Return code: 0 20:30:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:30:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:29 INFO - Reading from file tmpfile_stdout 20:30:29 INFO - Current package versions: 20:30:29 INFO - blessings == 1.6 20:30:29 INFO - blobuploader == 1.2.4 20:30:29 INFO - docopt == 0.6.1 20:30:29 INFO - functools32 == 3.2.3-2 20:30:29 INFO - jsonschema == 2.5.1 20:30:29 INFO - manifestparser == 1.1 20:30:29 INFO - mozInstall == 1.12 20:30:29 INFO - mozcrash == 1.0 20:30:29 INFO - mozdebug == 0.1 20:30:29 INFO - mozdevice == 0.51 20:30:29 INFO - mozfile == 1.2 20:30:29 INFO - mozhttpd == 0.7 20:30:29 INFO - mozinfo == 0.9 20:30:29 INFO - mozleak == 0.1 20:30:29 INFO - mozlog == 3.4 20:30:29 INFO - moznetwork == 0.27 20:30:29 INFO - mozprocess == 0.25 20:30:29 INFO - mozprofile == 0.28 20:30:29 INFO - mozrunner == 6.13 20:30:29 INFO - mozscreenshot == 0.1 20:30:29 INFO - mozsystemmonitor == 0.3 20:30:29 INFO - moztest == 0.8 20:30:29 INFO - mozversion == 1.4 20:30:29 INFO - psutil == 3.1.1 20:30:29 INFO - requests == 1.2.3 20:30:29 INFO - wsgiref == 0.1.2 20:30:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30: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')]} 20:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30: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')]} 20:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30: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 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30: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 20:30: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 20:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:29 INFO - 'HOME': '/Users/cltbld', 20:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:29 INFO - 'LOGNAME': 'cltbld', 20:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:29 INFO - 'MOZ_NO_REMOTE': '1', 20:30:29 INFO - 'NO_EM_RESTART': '1', 20:30:29 INFO - 'PAGER': '/bin/cat', 20:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:29 INFO - 'PWD': '/builds/slave/test', 20:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:29 INFO - 'SHELL': '/bin/bash', 20:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:29 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:29 INFO - 'USER': 'cltbld', 20:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:29 INFO - 'XPC_FLAGS': '0x0', 20:30:29 INFO - 'XPC_SERVICE_NAME': '0', 20:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 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)) 20:30:29 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:30:29 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 20:30:29 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 20:30:29 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 20:30:29 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 20:30:30 INFO - Downloading marionette_driver-1.4.0.tar.gz 20:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 20:30: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)) 20:30: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)) 20:30:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:30:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:30:30 INFO - Installing collected packages: marionette-driver 20:30:30 INFO - Running setup.py install for marionette-driver 20:30:31 INFO - Successfully installed marionette-driver 20:30:31 INFO - Cleaning up... 20:30:31 INFO - Return code: 0 20:30:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109d5ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d5e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1fadc8fa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, '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.GY4UC5oWFu/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.RmverSR5Md/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 20:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:31 INFO - 'HOME': '/Users/cltbld', 20:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:31 INFO - 'LOGNAME': 'cltbld', 20:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:31 INFO - 'MOZ_NO_REMOTE': '1', 20:30:31 INFO - 'NO_EM_RESTART': '1', 20:30:31 INFO - 'PAGER': '/bin/cat', 20:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:31 INFO - 'PWD': '/builds/slave/test', 20:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:31 INFO - 'SHELL': '/bin/bash', 20:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:31 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:31 INFO - 'USER': 'cltbld', 20:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:31 INFO - 'XPC_FLAGS': '0x0', 20:30:31 INFO - 'XPC_SERVICE_NAME': '0', 20:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:30:31 INFO - Cleaning up... 20:30:31 INFO - Return code: 0 20:30:31 INFO - Running post-action listener: _resource_record_post_action 20:30:31 INFO - Running post-action listener: _start_resource_monitoring 20:30:31 INFO - Starting resource monitoring. 20:30:31 INFO - [mozharness: 2017-05-26 03:30:31.507741Z] Finished create-virtualenv step (success) 20:30:31 INFO - [mozharness: 2017-05-26 03:30:31.508902Z] Running install step. 20:30:31 INFO - Running pre-action listener: _resource_record_pre_action 20:30:31 INFO - Running main action method: install 20:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:31 INFO - Reading from file tmpfile_stdout 20:30:31 INFO - Detecting whether we're running mozinstall >=1.0... 20:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:30:31 INFO - Reading from file tmpfile_stdout 20:30:31 INFO - Output received: 20:30:31 INFO - Usage: mozinstall [options] installer 20:30:31 INFO - Options: 20:30:31 INFO - -h, --help show this help message and exit 20:30:31 INFO - -d DEST, --destination=DEST 20:30:31 INFO - Directory to install application into. [default: 20:30:31 INFO - "/builds/slave/test"] 20:30:31 INFO - --app=APP Application being installed. [default: firefox] 20:30:31 INFO - mkdir: /builds/slave/test/build/application 20:30:31 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'] 20:30:31 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 20:30:52 INFO - Reading from file tmpfile_stdout 20:30:52 INFO - Output received: 20:30:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:30:52 INFO - Running post-action listener: _resource_record_post_action 20:30:52 INFO - [mozharness: 2017-05-26 03:30:52.888929Z] Finished install step (success) 20:30:52 INFO - [mozharness: 2017-05-26 03:30:52.889089Z] Running run-tests step. 20:30:52 INFO - Running pre-action listener: _resource_record_pre_action 20:30:52 INFO - Running main action method: run_tests 20:30:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:30:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:30:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:30:52 INFO - 2017-05-25 20:30:52.903 screenresolution[2170:15312] starting screenresolution argv=screenresolution get 20:30:52 INFO - 2017-05-25 20:30:52.906 screenresolution[2170:15312] Display 0: 1600x1200x32@60 20:30:52 INFO - 2017-05-25 20:30:52.912 screenresolution[2171:15315] starting screenresolution argv=screenresolution list 20:30:52 INFO - Available Modes on Display 0 20:30:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:30:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:30:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:30:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:30:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:30:53 INFO - Intel Iris: 20:30:53 INFO - Chipset Model: Intel Iris 20:30:53 INFO - Type: GPU 20:30:53 INFO - Bus: Built-In 20:30:53 INFO - VRAM (Dynamic, Max): 1536 MB 20:30:53 INFO - Vendor: Intel (0x8086) 20:30:53 INFO - Device ID: 0x0a2e 20:30:53 INFO - Revision ID: 0x0009 20:30:53 INFO - Displays: 20:30:53 INFO - Display: 20:30:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:30:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:30:53 INFO - Display Serial Number: 1600x1200 60 20:30:53 INFO - Main Display: Yes 20:30:53 INFO - Mirror: Off 20:30:53 INFO - Online: Yes 20:30:53 INFO - Rotation: Supported 20:30:53 INFO - Return code: 0 20:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:30:53 INFO - Python 2.7.3 20:30:53 INFO - Return code: 0 20:30:53 INFO - grabbing minidump binary from tooltool 20:30:53 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')]} 20:30:53 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 0x109a6ad30>, '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 0x109a1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a5fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:30:53 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 20:30:53 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 20:30:53 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 20:30:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:30:53 INFO - Return code: 0 20:30:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:30:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:30:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:30:53 INFO - ENV: RUST_BACKTRACE is now 1 20:30:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:30:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:30:53 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/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 20:30:53 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/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0314 --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 20:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GY4UC5oWFu/Render', 20:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:53 INFO - 'HOME': '/Users/cltbld', 20:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:53 INFO - 'LOGNAME': 'cltbld', 20:30:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:30:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:53 INFO - 'MOZ_NO_REMOTE': '1', 20:30:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:30:53 INFO - 'NO_EM_RESTART': '1', 20:30:53 INFO - 'PAGER': '/bin/cat', 20:30:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:53 INFO - 'PWD': '/builds/slave/test', 20:30:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:53 INFO - 'RUST_BACKTRACE': '1', 20:30:53 INFO - 'SHELL': '/bin/bash', 20:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RmverSR5Md/Listeners', 20:30:53 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 20:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:53 INFO - 'USER': 'cltbld', 20:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:53 INFO - 'XPC_FLAGS': '0x0', 20:30:53 INFO - 'XPC_SERVICE_NAME': '0', 20:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:53 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/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 20:30:53 INFO - mozversion application_buildid: 20170525183704 20:30:53 INFO - mozversion application_changeset: 0874cf4bb194d381a3afaa51276b6cee22f82211 20:30:53 INFO - mozversion application_display_name: Nightly 20:30:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:30:53 INFO - mozversion application_name: Firefox 20:30:53 INFO - mozversion application_remotingname: firefox 20:30:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:30:53 INFO - mozversion application_vendor: Mozilla 20:30:53 INFO - mozversion application_version: 55.0a1 20:30:53 INFO - mozversion platform_buildid: 20170525183704 20:30:53 INFO - mozversion platform_changeset: 0874cf4bb194d381a3afaa51276b6cee22f82211 20:30:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:30:53 INFO - mozversion platform_version: 55.0a1 20:30:53 INFO - using testdate: 1495769453 20:30:53 INFO - actual date: 1495769453 20:30:53 INFO - starting webserver on 'localhost:49629' 20:30:53 INFO - SUITE-START | Running 2 tests 20:30:53 INFO - TEST-START | dromaeo_css 20:30:53 INFO - operating with platform_type : mac_ 20:30:53 INFO - Initialising browser for dromaeo_css test... 20:30:53 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49629/getInfo.html -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpY8d_X9/profile 20:30:53 INFO - TEST-INFO | started process 2181 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49629/getInfo.html -foreground) 20:31:27 INFO - TEST-INFO | 2181: killed by SIGTERM 20:31:27 INFO - Browser initialized. 20:31:27 INFO - Running cycle 1/1 for dromaeo_css test... 20:31:27 INFO - TEST-INFO | started process 2191 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpY8d_X9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:31:29 INFO - PID 2191 | 2017-05-25 20:31:28.999 plugin-container[2192:15773] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 20:31:29 INFO - PID 2191 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:29 INFO - PID 2191 | 2017-05-25 20:31:29.002 plugin-container[2192:15773] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.CFPasteboardClient' 20:31:29 INFO - PID 2191 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:29 INFO - PID 2191 | 2017-05-25 20:31:29.002 plugin-container[2192:15773] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:31:29 INFO - PID 2191 | 2017-05-25 20:31:29.728 plugin-container[2193:15814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 20:31:29 INFO - PID 2191 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:29 INFO - PID 2191 | 2017-05-25 20:31:29.729 plugin-container[2193:15814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 20:31:29 INFO - PID 2191 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:29 INFO - PID 2191 | 2017-05-25 20:31:29.729 plugin-container[2193:15814] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:31:39 INFO - PID 2191 | RSS: Main: 242847744 20:31:39 INFO - PID 2191 | 20:34:40 INFO - PID 2191 | Cycle 1(1): loaded http://localhost:49629/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49629/tests/dromaeo/cssquery-ext.html) 20:34:42 INFO - PID 2191 | RSS: Main: 258519040 20:34:42 INFO - PID 2191 | 20:37:44 INFO - PID 2191 | Cycle 1(1): loaded http://localhost:49629/tests/dromaeo/cssquery-ext.html (next: http://localhost:49629/tests/dromaeo/cssquery-jquery.html) 20:37:45 INFO - PID 2191 | RSS: Main: 269672448 20:37:45 INFO - PID 2191 | 20:40:46 INFO - PID 2191 | Cycle 1(1): loaded http://localhost:49629/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49629/tests/dromaeo/cssquery-mootools.html) 20:40:47 INFO - PID 2191 | RSS: Main: 269758464 20:40:47 INFO - PID 2191 | 20:43:48 INFO - PID 2191 | Cycle 1(1): loaded http://localhost:49629/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49629/tests/dromaeo/cssquery-prototype.html) 20:43:49 INFO - PID 2191 | RSS: Main: 273485824 20:43:49 INFO - PID 2191 | 20:46:52 INFO - PID 2191 | Cycle 1(1): loaded http://localhost:49629/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49629/tests/dromaeo/cssquery-yui.html) 20:46:52 INFO - PID 2191 | RSS: Main: 273575936 20:46:52 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | Cycle 1(1): loaded http://localhost:49629/tests/dromaeo/cssquery-yui.html (next: http://localhost:49629/tests/dromaeo/cssquery-dojo.html) 20:49:54 INFO - PID 2191 | RSS: Main: 275390464 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | __start_tp_report 20:49:54 INFO - PID 2191 | _x_x_mozilla_page_load 20:49:54 INFO - PID 2191 | _x_x_mozilla_page_load_details 20:49:54 INFO - PID 2191 | |i|pagename|runs| 20:49:54 INFO - PID 2191 | |0;dojo.html;3068.8465576721164;3557.8576856925724;3584.677379035886;3602;3603.9459408108874;6101.489633126343;6169.598976066558;6218.937810621893;6221.720022598983;6224.657643829588;5046.99060187962;5287.603429742775;6120.020512922639;6134.008014331981;6240.095186197998;5459.972700136514;5468.808591699291;5472.480114389146;5484.204790305404;5615.719214039282;4975.875603109933;5521.420250873661;5575.944240557583;5613.158026296068;5641.464060914206;5345.545628621572;5427.810026649087;5491.450854914477;5523.199136125239;5560.7219639018085;5442.537384322337;5448.210009548593;5478.945210547883;5485.369182544018;5513.173024046385;5417.295751552312;5420.756065977041;5436.157395603688;5450.754716037788;5460.34475862899;6123.969380153071;6237.563370564062;6239.625622462667;6241.875162496724;6245.968770156165;6028;6575.243846957614;6584.078229047937;6710.93289067108;6711.6979735912;6806.251312355637;6821;6828.965855170742;6832;6864.519483636147;6296.905546416808;6321.65230912295;6322.525810564226;6323.52573556985;6326.335734747877;6695;6778.254392016875;6847.828804279933;6887.724491020404;6919.930800692029;5313.282706834605;5589.692566908859;6310.305866354697;6437.452816510556;6591.110200123018;6672.766453174116;6723;6742.49431292655;6743.831404214836;6765.763198288135;6026.523501742014;6467.029945508118;6532;6563.737450502022;6565.770198043141;6480.449161821298;6546.672666366662;6556.114924485229;6591.6374599397495;6618.205815302098;4745.311929770164;5323.733813309319;5364.088105022156;5366.7853285868905;5399.892002159935;4772.618190544748;4775.546323099369;4793.664443488992;4795.472498025214;4823.5658790709;6207.441330280297;6240.594361366497;6243.906341404882;6299.96850015747;6309.400606942333;6779.186497620317;6822.692978815965;6833.58998460094;6839.794806155824;6858.6570671466225;5786.710664466844;5890.9410905891245;6247;6284.088807122942;6322.304546499881;5778.913316300259;5802;5856.297244330623;6007.948608993409;6020.638761674226;6871.896921546081;6934.445244380337;6975.441964642715;6983.336583024605;7023.719051238098;6843.076184715199;6845.315468453115;6848.7260509579055;6851.246362900177;6891.241963383924;6709.7316107355155;6778;6929.930700692928;6935.930640693528;6962.23415424313;5498.615096943336;6540.640264785482;6558.344165583403;6646.468282537482;6720.563163394364;6824.863502729819;6840.897386539106;6881.759138430331;6883.414909732729;6904.965475172592;6352.205974253218;6431.067495213074;6491.4482269005775;6538.378854008862;6638.83402914931;6547.821392149459;6595.340465953366;6596.142501474969;6623.635700036544;6630.30381809903;6384.7446102157255;6469.061986012096;6478.773242936474;6485.254195767548;6489.9351006491215;6404.455621272244;6627.900581491185;6634.668266586747;6636.83407914806;6653.900191497035;5393.732472868951;6298.811035668938;6399.296077431572;6486.318936511599;6487.772927947499 20:49:54 INFO - PID 2191 | |1;ext.html;656.3829999800188;771.8301973565814;827.8923739913811;1088.9727756806085;1215.434822807395;39128.804355978216;40154.59845401549;40160.79919600405;40179;40317.99205019872;39950.40074398887;40042.399364009565;40368.38526458938;40394.39408408876;40457.39313910294;7845;8009.719659811908;8343.207395297437;8527.104654011333;8538.188872057144;8407.24334809867;8506.489610623383;8511.404201705884;8520.74437766868;8608.52653104079;8368.832623347529;8432.662693492253;8501.277391421736;8524.147585241457;8554.828903421927;8422.452540584904;8434.83130337396;8458.619362128687;8487.872681909776;8503.659853605848;6652.833679158011;8167.346612271063;8233.217844304781;8253.463524870864;8326.417150799447;37336.69321573759;37543;37723.811380943196;37875.43186852199;37970.430443543366;3546.131197856516;3583.802890841003;3584.551931008624;3590.2460483298537;3595.1551385424405;30326.241843953856;30629.540556891665;30687.079387618407;30697.69302306993;30761.230969225726;3068.2636167319906;3081.568580398746;3095.876164953399;3099.798513096664;3110.626724793039;231134;233516.66483335118;265409.67295163596;275390;275994;47161.5283847164;47281.290780638315;47409.051818964115;47553;47676.761616191696;49304.260436093486;49461.50538494641;49529;49549.25676114861;49649.75175124101;11769.823452648217;13987.860121398655;14018.439262429389;14152.221627810364;14156.221407822462;11583.942080289544;11608.709782255512;11628.953394194563;11643.941780291045;11655.825162622568;13537.526186583422;13569.1858488491;13586.932065339808;13599.184048957095;13695.383707733175;5029;5536.169574563767;6042.093685947143;6086.969565152146;6092;968.5399435268332;973.5716284834791;990.8513722941616;1001.0490034467285;1009.8737657792776;22561.774382255968;24256.878715606308;24478.265652030466;24502.26493205207;24657.753422465903;1094.895984881435;1120.2102517724984;1121.775644871029;1153.5385845661635;1156.7166044319197;11971.52113915451;12598.748025039631;12647.620571382873;12672.429740661692;12681.492740290285;301811;302838;302937;302945;303472;43254.783726081165;52911;53165.468345316054;53298;53438.73280633572;1475.3877140986585;1502.136271643809;1527.3127092808058;1530.6632540840997;1534.110216074674;23883.641745373487;24072.638910416706;24134.03463861426;24181.63727544053;24234.515309693354;37723.245535088594;37822;37849.81075094607;37884.86345409532;37928;210321;254178;258663;259109.70445148408;260162;33599.49600755942;34026.829865850515;34189.65810341964;34842.65157348394;34887.47668785021;37326.440103399014;43404;45169;45588.088238235716;45741.771291143334;26361.604575930993;27856;29901.401971960004;31318.530222047142;31377.05868823939;23515.88242058847;30859.84570077135;31101.844490777403;31120;31242.21894452566;35963.28073438464;37088.81455592705;37122.8143859279;37145.071373215884;37587.62412375951;27586.620668966872;27923.44450221579;31276.218094546908;31297.530537041508;31535.89624363228;23610.88194559016;24114.75885241125;24387.39031524226;24530.754692452847;24639.753602463745 20:49:54 INFO - PID 2191 | |2;jquery.html;427.90586071064376;432.4162380785941;440.19224722633965;451.8418553506271;457.1976181800939;20442.795572044273;20535.897320513377;21186.152553897846;21947.780522194815;21992.78007219927;19880.10539525721;22546.774532254673;22871.656925146093;23175.420614484647;23823.166189183383;6000.099985002263;6220.564560480768;6223.564350495467;6228.034654628517;6250.781222657208;5957.553183511232;6087.452129308362;6109.938900610981;6130.570860039799;6220.502359811204;5340.332458442695;5762.616971926728;6046.879062418749;6077.848053798668;6098.939010609904;5147.871303217431;5975.846786483396;6031.668258245794;6058;6071.089336599502;5404.675719456845;5410.269613602192;5410.97294513525;5413.188021796723;5436.238926550286;27233.591496127574;27588.862055689595;27881.86059069712;28011.299717507023;28130;2152.0638522242875;2164.5562659654734;2177.433867194525;2178.9346319610436;2182.0289970962926;17307.653846923116;17514.737278940825;17708.72164979199;17908.194131263994;17962.281508739634;2133.210712036541;2139.2833400810746;2171.0881429799524;2185.7923497267893;2188.004457971619;128907.35546322302;151568;153761;155595;157579;12801.743965120831;12843.48626054966;12903.419346129447;12905.096643234883;12906.483740650268;16665.25006374716;16711.91644041772;16724.414645487348;16731.247093880804;16754.99470031802;8797.420445720276;9116.316276279305;9118.221946720458;9212.170904618617;9254.213391861633;6617.8014659560295;7664.501807382502;7847.450678452454;8637.740867773977;8702.434341767765;7993.440459167919;8142.715004974797;8171.305439037748;8184.836303273901;8197.50814951093;2189.6168170570086;2199.3621849663737;2207.4812419081504;2217.5786600545844;2365.562370961378;904.6562306323555;930.6417029443578;946.8248374050685;962.6438217859437;966.5215718219526;17857.28570857151;21150.576988460452;21411.715297082224;21440.24959126423;21767.34697959064;993.145894530703;1000.4493035794635;1010.939343639384;1018.5772904244656;1030.7629245273629;10463.84304235437;11109.888901110886;11136.220464567565;11244.718882028015;11266.774664506664;506758;515055;515091;524367;525965;118054.8194518061;118494.8150518501;122373.04575202584;127910;128424.36817238745;1046.9476526173812;1214.368528365245;1241.7392347607104;1244.9066320025854;1249.3690686203408;22534.32397028092;22622.832803407207;22890.656640150744;22969.88515057481;23142.305730828102;58327.87557306592;58542.707286463294;58932.11601826062;59012;59213.703931480064;77605;77670.6116469414;78162.6091869537;79362;79542.6022869882;9059.411138275991;9081.228095611947;9174.220191283815;9200.631974721204;9346.719598412059;13185.53850615224;14081.366338514586;14315.427382904567;14515.637109072357;14546.272686365852;10145.391276523433;10784.568617255221;11151.386673733023;11216.158788090763;11596.94201528987;11140.275882067639;11169.050630696482;11415.828762568402;11477.024452921596;11666.941665291619;11549.48027338755;11711.707207319885;11852.703682407664;11966.521339146337;12008.939955300517;7465.552066876005;9016.909830901606;9069.274458043472;9168.862467063132;9274.907250927405;9374.343795934492;11491.712707182387;11777.116716246144;11787.469563869476;11884.405779711153 20:49:54 INFO - PID 2191 | |3;mootools.html;1379.5654368873804;1435.5047508609525;1440.5030264558723;1444.0830072903702;1456.7305048566016;11659.358735269558;11671.883281167185;11787.70530736732;12114.091443141766;12202.267863928171;9841.753956151082;11837;11946.581869634565;12117.515299388037;12151.939240303785;2454.04292325993;2484.714257860342;2499.7750202481775;2503.8748062596887;2510.6108553174286;2134.5197330600568;2487.465194983078;2520.218732193017;2548.5157820014138;2555.6038813983864;2088.457001179697;2469.493753780471;2491.6885389326335;2522.155078048856;2523.6466894634764;2425.5678711665573;2438.6463962725484;2442.8534287942693;2444.816638752083;2448.5592593333195;2432.19737486629;2432.3311089450544;2447.8286519943613;2534.822562420631;2539.9365015874564;11723.824142637868;11787.292762434196;11839.230450020721;11898.821517677155;11942.223755455867;6257.311695713468;6441.645709485976;6454.1609590752905;6459.935400645981;6478.222613286389;3924.2347742190286;4076.7961601919787;4141.668666506672;4150.605692459242;4167.874963751093;1189.6847335456112;1214;1220.6216073017392;1221.1940119521073;1234.0251201550764;15324.463643772526;15723.13522756248;16427.178641068018;16563.83436165635;16581.91709041459;5316.202569614511;5355.196720491879;5546.334439867242;5556.944430555724;5587.1339942309005;11759.647210583526;11884.40577971098;11886.108541859396;11899.643010709693;11909.344986025679;6234.189555357774;6311.842203944938;6339.904901426482;6348.682565871688;6353.491720662336;5865.149553314745;5894.38108998549;5899.380565040609;6019.157317975487;6109.84725381869;4290.334998075304;4322.37325587788;4335.2196604610845;4349.543297953733;4435.534268901783;1251.743392604514;1273.1215461331838;1275.5089290623177;1286.6783304173957;1289.2522337044493;794.4983980277281;795.9641816118288;820.6471217376466;822.5352675738188;843.843888880559;6116.48009919153;6272.8118156455375;6330.303666596671;6365.936340636627;6428.6785660717715;586.865021045162;602.8733965867129;606.3300053440972;608.178958406157;613.2916481463916;3412.805518068656;3647.6321379482692;3875.457435958968;3894.9221015580097;4450.332450132441;13957.232352220726;14563.271836408137;14695;14922.477713280201;14967.2787629424;14266.215358155401;14541.98206125561;14575.781363279559;14781.334839932013;14859.777103343458;8212.671493140208;8361.665533378718;8456.450330728483;8461.196186362286;8514.552526070584;5372.650777699437;6561.737530498726;6642.202935647753;6791.185057793096;6826.761063362758;9654;12894.935525322313;12979.221246725227;13168.670783230113;13311.068225224528;13318;13353.265570393332;13448.529301474402;13598;13656.93171534136;8108.189181082081;8178.509289442414;8276.048254450816;8300.916990830014;8309.459885107448;9208.907910921072;10404.167666586503;10457.633982810565;10583.259171858204;10594.947025264824;7527.3601743850195;8872.64509419616;9124.27005839518;9174.403663761834;9191.632334706537;8687.174718401742;8758.03661597211;8783.72635967794;8870.068642792414;9049.77375565616;7345.75122229233;7676.270754278335;8045.396595255263;8063.35493160534;8141.348692104736;5976.701164941823;6031.336552979081;6071;6075.27096748393;6223.719932603134;6033.155358249936;6066.059760737093;6119.816405507842;6140.078988151634;6229.750809967551 20:49:54 INFO - PID 2191 | |4;prototype.html;357.1998722860792;399.4387885021546;400.0977795072112;400.399400898652;407.9632833045025;5991.970040149804;6308.779192728255;6343.714532846021;6352;6359.650219237946;4245.681573881956;6203.844903877405;6219.9689001554925;6251.09359142924;6286.151369565118;4528.252838281696;4753.738544380058;4774.73738944358;4786.83246086387;4796.83211087612;4696.624270058404;4810.278458231258;4813.133635945528;4823.372961514998;4834.661573689843;4763.14263432582;4771.90456190876;4778.331033655291;4784.760761961906;4792.257200133985;4713.976430117862;4762.785674644615;4787.760611969423;4803.303520989454;4811.663183577133;4192.706510544263;4240.406343111967;4246.745195288293;4257.489101307832;4272.2950713132295;5201.323827902348;5541.27963364764;5834.154047663065;5962.612430192024;6198.287196972361;5037.823676171316;5696.259486266758;5696.914546281809;5713.857153571153;5726.971365143189;6083.48290395317;6613.503987200931;6722.630255335955;6754.898676519856;6757.662116894185;4814.374131362901;4875.609951203895;4881.1213981483315;4893.730844803535;4897.583705385011;6505.739770409178;6507.154069970874;7093.772333588205;7094.219635840053;7149.034015645739;4530.22986092365;4684.929726054112;4807.158747219222;4979.833772309057;4981.526754958273;4906.779194936165;5059.87350316245;5068.949310506848;5070.949290507047;5075.3148324975655;4417.60241578253;4862.17343051682;5370.301860758076;5432.674039557639;5500.532454741392;5797.449242321973;6696.408498903373;6708.2956289589865;6718.966405168041;6800.591964482147;1468.4933697874217;1719.3208682570314;1756.5959829239346;1760.4718584424622;1764.97352539712;1195.6950977500856;1214.1925619463134;1216.647172320035;1216.983818511535;1228.416502161466;5182.689038657617;6251;6263.185785847902;6267.467265282411;6325.240971083499;6681.298463661343;6907.585544867224;6923.653817309114;6941.375276225063;6975.79072627822;4856.927146092811;5623.550115990711;5693.544516438675;5795.710214489259;6277.246730392382;3238;3434.3989801784587;3647.7962272450027;3656.6526180012856;3674.9053039767077;6078.696065196811;6883.690233939485;7088.220295767462;7154.678039488235;7178.748743793943;6020.759169633166;6859.691313890787;6884.793456196322;7131.82170445743;7234.710611575478;5830.892130495193;5930.22907022093;6259.873222819936;6310.274318453345;7154.678039488235;5485.725713714378;5756.395578464201;6975.372216500539;7029.894551581628;7068.929310706827;5483.917741233964;6863.039174515671;6915.861682766417;6921.988934813372;6981.603679264269;5954;6821;7208.639568021683;7215.6031418270395;7236.444164504656;4388.3105004572835;4840.709557426425;5048.5203905628905;5059.6458247923765;5089.389273287229;5711.600187986801;5787.421257874179;6683.498737594603;6888.552244104118;7015.018617087615;6344.492440604648;6521.315261897622;6663.067170596218;6674.6662666865495;6782.525223234326;6329.620222786648;6646.2356828965285;6715.328467153246;6730.899036514358;6740.966295168493;6402.263739669997;6569.9014514783275;6592.17597800275;6618.007298905202;6630.3038180992235;5224.817131400383;6241.375862413904;6738;6763.560368576027;6770.695318710768;5493.478119578634;6360.713767880364;6496.329573790793;6584.341565843569;6585.045168450643 20:49:54 INFO - PID 2191 | |5;yui.html;684.5624189202672;704.535006895449;831.3515457942802;847.7838151271425;858.9742307730768;13511.459541618337;17185.484435466926;17201;19117.23531058756;19827.405177844652;15745.212739363014;18110.728339074893;18160.36438724645;18666;18666.53333666659;978.990210097897;988.2341185581196;997.0677416615451;1004.6031817432109;1022.2486472442748;837.3217693668117;981.3670182732134;1001.8647482589867;1008.2841182760212;1009.3540134314045;1009.3540134314045;1041.671873359889;1072.8766191887917;1077.1974878715369;1080.8054550180964;1052.726291164299;1061.3525749292926;1061.9309744866598;1066.5040756048397;1069.315637991686;702.2345643248832;765.9157492675802;783.4907310248327;784.3176436500281;785.4815821557744;17457.301707931667;17478.213480393286;17535.298588056463;17673.381431649832;17726;16571.83428165715;16851.831481685273;16868.987860728397;16874.409395671093;17563.64872702539;12731.809022864665;14347.42610295587;14428.495002674961;14614.123152610877;14646.63383415423;3243.3189030303665;3553.093961039944;3604.819759012039;3638.745287829853;3640.4539319102346;127443.36278318641;138064;145340;147406;147892.2605386977;11565.76868462626;13726.519571814939;13763.449462021606;13918.30408479572;13967.301634918213;25885.352866178495;26218.737812621628;26371;26383.868080659475;26387;7560.773176804704;7696.4227682924;7718.691252349843;7722.652480638385;7734.110577283572;3256.723178529804;3875.8255878485534;3895.220955808793;3902.824372903226;3906.9609303907164;4395.780210989437;4409.272470042407;4424.867253982386;4490.685652004394;4503.707259028218;734.9816254593678;839.1524560194126;864.1660797330607;864.8400045991386;864.9740507784777;634.5082561015158;665.6605131382933;667.3926726678699;672.9562578432386;689.1798759476308;10963.451827408599;11209.71975700614;11994.700132496757;12025.098117641212;12157.817632735516;636.0999186151636;646.7833275852548;649.4966401039233;656.2584279763931;665.0456594786497;4070.592940705965;4439.400680908101;5561.6663000220115;5630.324361076615;5748.281464816898;128756.35621821831;137375;146250;147188.5843424699;148232.2588387051;106135.46932265443;111852.44073779578;113435.86564134253;113708;117394;97525.04949901105;100107.49946250368;100269;100435.49782251188;101558;8971.775705607412;10077.69766906994;11413.315201087962;11498.195126341076;12236.75526489483;36457.63542364542;37269.6273037277;37375.62624373721;37594;38006;48347.27479087746;48815.75592122017;49003.01993960029;49495.75252123716;49545.50454495409;16661;16667.74998375001;16922.661546768748;16953.406630767862;17145.228464718868;16273.75589366184;17464.340887615657;18345.54136146607;18786.96671683016;18826;17037.914810425867;17212.483625491255;17305.65388692194;17367.47897563075;17472.12639368001;16800.411985580446;17188.31246750116;17200.569985749957;17220.543806646147;17372.565685857953;13747.175169489863;16620.58448538796;16809.41167059147;17018;17121.82878171252;11831;12082.039900224501;12135.393230338625;12222.87777122253;12251.816222756488;10910.854360292054;12577.371131443575;13383.39774710121;13502.257375844423;13563.525276615272 20:49:54 INFO - PID 2191 | __end_tp_report 20:49:54 INFO - PID 2191 | __start_cc_report 20:49:54 INFO - PID 2191 | _x_x_mozilla_cycle_collect,399 20:49:54 INFO - PID 2191 | __end_cc_report 20:49:54 INFO - PID 2191 | __startTimestamp1495770594630__endTimestamp 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | ------- Summary: start ------- 20:49:54 INFO - PID 2191 | Number of tests: 6 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | [#0] dojo.html Cycles:165 Average:6141.68 Median:6326.34 stddev:752.76 (11.9%) stddev-sans-first:715.44 20:49:54 INFO - PID 2191 | Values: 3068.8 3557.9 3584.7 3602.0 3603.9 6101.5 6169.6 6218.9 6221.7 6224.7 5047.0 5287.6 6120.0 6134.0 6240.1 5460.0 5468.8 5472.5 5484.2 5615.7 4975.9 5521.4 5575.9 5613.2 5641.5 5345.5 5427.8 5491.5 5523.2 5560.7 5442.5 5448.2 5478.9 5485.4 5513.2 5417.3 5420.8 5436.2 5450.8 5460.3 6124.0 6237.6 6239.6 6241.9 6246.0 6028.0 6575.2 6584.1 6710.9 6711.7 6806.3 6821.0 6829.0 6832.0 6864.5 6296.9 6321.7 6322.5 6323.5 6326.3 6695.0 6778.3 6847.8 6887.7 6919.9 5313.3 5589.7 6310.3 6437.5 6591.1 6672.8 6723.0 6742.5 6743.8 6765.8 6026.5 6467.0 6532.0 6563.7 6565.8 6480.4 6546.7 6556.1 6591.6 6618.2 4745.3 5323.7 5364.1 5366.8 5399.9 4772.6 4775.5 4793.7 4795.5 4823.6 6207.4 6240.6 6243.9 6300.0 6309.4 6779.2 6822.7 6833.6 6839.8 6858.7 5786.7 5890.9 6247.0 6284.1 6322.3 5778.9 5802.0 5856.3 6007.9 6020.6 6871.9 6934.4 6975.4 6983.3 7023.7 6843.1 6845.3 6848.7 6851.2 6891.2 6709.7 6778.0 6929.9 6935.9 6962.2 5498.6 6540.6 6558.3 6646.5 6720.6 6824.9 6840.9 6881.8 6883.4 6905.0 6352.2 6431.1 6491.4 6538.4 6638.8 6547.8 6595.3 6596.1 6623.6 6630.3 6384.7 6469.1 6478.8 6485.3 6489.9 6404.5 6627.9 6634.7 6636.8 6653.9 5393.7 6298.8 6399.3 6486.3 6487.8 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | [#1] ext.html Cycles:180 Average:42446.73 Median:34108.24 stddev:70766.13 (207.5%) stddev-sans-first:70895.09 20:49:54 INFO - PID 2191 | Values: 656.4 771.8 827.9 1089.0 1215.4 39128.8 40154.6 40160.8 40179.0 40318.0 39950.4 40042.4 40368.4 40394.4 40457.4 7845.0 8009.7 8343.2 8527.1 8538.2 8407.2 8506.5 8511.4 8520.7 8608.5 8368.8 8432.7 8501.3 8524.1 8554.8 8422.5 8434.8 8458.6 8487.9 8503.7 6652.8 8167.3 8233.2 8253.5 8326.4 37336.7 37543.0 37723.8 37875.4 37970.4 3546.1 3583.8 3584.6 3590.2 3595.2 30326.2 30629.5 30687.1 30697.7 30761.2 3068.3 3081.6 3095.9 3099.8 3110.6 231134.0 233516.7 265409.7 275390.0 275994.0 47161.5 47281.3 47409.1 47553.0 47676.8 49304.3 49461.5 49529.0 49549.3 49649.8 11769.8 13987.9 14018.4 14152.2 14156.2 11583.9 11608.7 11629.0 11643.9 11655.8 13537.5 13569.2 13586.9 13599.2 13695.4 5029.0 5536.2 6042.1 6087.0 6092.0 968.5 973.6 990.9 1001.0 1009.9 22561.8 24256.9 24478.3 24502.3 24657.8 1094.9 1120.2 1121.8 1153.5 1156.7 11971.5 12598.7 12647.6 12672.4 12681.5 301811.0 302838.0 302937.0 302945.0 303472.0 43254.8 52911.0 53165.5 53298.0 53438.7 1475.4 1502.1 1527.3 1530.7 1534.1 23883.6 24072.6 24134.0 24181.6 24234.5 37723.2 37822.0 37849.8 37884.9 37928.0 210321.0 254178.0 258663.0 259109.7 260162.0 33599.5 34026.8 34189.7 34842.7 34887.5 37326.4 43404.0 45169.0 45588.1 45741.8 26361.6 27856.0 29901.4 31318.5 31377.1 23515.9 30859.8 31101.8 31120.0 31242.2 35963.3 37088.8 37122.8 37145.1 37587.6 27586.6 27923.4 31276.2 31297.5 31535.9 23610.9 24114.8 24387.4 24530.8 24639.8 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | [#2] jquery.html Cycles:180 Average:34523.05 Median:22747.24 stddev:87958.08 (386.7%) stddev-sans-first:88167.57 20:49:54 INFO - PID 2191 | Values: 427.9 432.4 440.2 451.8 457.2 20442.8 20535.9 21186.2 21947.8 21992.8 19880.1 22546.8 22871.7 23175.4 23823.2 6000.1 6220.6 6223.6 6228.0 6250.8 5957.6 6087.5 6109.9 6130.6 6220.5 5340.3 5762.6 6046.9 6077.8 6098.9 5147.9 5975.8 6031.7 6058.0 6071.1 5404.7 5410.3 5411.0 5413.2 5436.2 27233.6 27588.9 27881.9 28011.3 28130.0 2152.1 2164.6 2177.4 2178.9 2182.0 17307.7 17514.7 17708.7 17908.2 17962.3 2133.2 2139.3 2171.1 2185.8 2188.0 128907.4 151568.0 153761.0 155595.0 157579.0 12801.7 12843.5 12903.4 12905.1 12906.5 16665.3 16711.9 16724.4 16731.2 16755.0 8797.4 9116.3 9118.2 9212.2 9254.2 6617.8 7664.5 7847.5 8637.7 8702.4 7993.4 8142.7 8171.3 8184.8 8197.5 2189.6 2199.4 2207.5 2217.6 2365.6 904.7 930.6 946.8 962.6 966.5 17857.3 21150.6 21411.7 21440.2 21767.3 993.1 1000.4 1010.9 1018.6 1030.8 10463.8 11109.9 11136.2 11244.7 11266.8 506758.0 515055.0 515091.0 524367.0 525965.0 118054.8 118494.8 122373.0 127910.0 128424.4 1046.9 1214.4 1241.7 1244.9 1249.4 22534.3 22622.8 22890.7 22969.9 23142.3 58327.9 58542.7 58932.1 59012.0 59213.7 77605.0 77670.6 78162.6 79362.0 79542.6 9059.4 9081.2 9174.2 9200.6 9346.7 13185.5 14081.4 14315.4 14515.6 14546.3 10145.4 10784.6 11151.4 11216.2 11596.9 11140.3 11169.1 11415.8 11477.0 11666.9 11549.5 11711.7 11852.7 11966.5 12008.9 7465.6 9016.9 9069.3 9168.9 9274.9 9374.3 11491.7 11777.1 11787.5 11884.4 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | [#3] mootools.html Cycles:180 Average:6937.80 Median:3909.58 stddev:4420.66 (113.1%) stddev-sans-first:4413.33 20:49:54 INFO - PID 2191 | Values: 1379.6 1435.5 1440.5 1444.1 1456.7 11659.4 11671.9 11787.7 12114.1 12202.3 9841.8 11837.0 11946.6 12117.5 12151.9 2454.0 2484.7 2499.8 2503.9 2510.6 2134.5 2487.5 2520.2 2548.5 2555.6 2088.5 2469.5 2491.7 2522.2 2523.6 2425.6 2438.6 2442.9 2444.8 2448.6 2432.2 2432.3 2447.8 2534.8 2539.9 11723.8 11787.3 11839.2 11898.8 11942.2 6257.3 6441.6 6454.2 6459.9 6478.2 3924.2 4076.8 4141.7 4150.6 4167.9 1189.7 1214.0 1220.6 1221.2 1234.0 15324.5 15723.1 16427.2 16563.8 16581.9 5316.2 5355.2 5546.3 5556.9 5587.1 11759.6 11884.4 11886.1 11899.6 11909.3 6234.2 6311.8 6339.9 6348.7 6353.5 5865.1 5894.4 5899.4 6019.2 6109.8 4290.3 4322.4 4335.2 4349.5 4435.5 1251.7 1273.1 1275.5 1286.7 1289.3 794.5 796.0 820.6 822.5 843.8 6116.5 6272.8 6330.3 6365.9 6428.7 586.9 602.9 606.3 608.2 613.3 3412.8 3647.6 3875.5 3894.9 4450.3 13957.2 14563.3 14695.0 14922.5 14967.3 14266.2 14542.0 14575.8 14781.3 14859.8 8212.7 8361.7 8456.5 8461.2 8514.6 5372.7 6561.7 6642.2 6791.2 6826.8 9654.0 12894.9 12979.2 13168.7 13311.1 13318.0 13353.3 13448.5 13598.0 13656.9 8108.2 8178.5 8276.0 8300.9 8309.5 9208.9 10404.2 10457.6 10583.3 10594.9 7527.4 8872.6 9124.3 9174.4 9191.6 8687.2 8758.0 8783.7 8870.1 9049.8 7345.8 7676.3 8045.4 8063.4 8141.3 5976.7 6031.3 6071.0 6075.3 6223.7 6033.2 6066.1 6119.8 6140.1 6229.8 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | [#4] prototype.html Cycles:180 Average:5437.02 Median:5814.17 stddev:1619.14 (27.8%) stddev-sans-first:1578.15 20:49:54 INFO - PID 2191 | Values: 357.2 399.4 400.1 400.4 408.0 5992.0 6308.8 6343.7 6352.0 6359.7 4245.7 6203.8 6220.0 6251.1 6286.2 4528.3 4753.7 4774.7 4786.8 4796.8 4696.6 4810.3 4813.1 4823.4 4834.7 4763.1 4771.9 4778.3 4784.8 4792.3 4714.0 4762.8 4787.8 4803.3 4811.7 4192.7 4240.4 4246.7 4257.5 4272.3 5201.3 5541.3 5834.2 5962.6 6198.3 5037.8 5696.3 5696.9 5713.9 5727.0 6083.5 6613.5 6722.6 6754.9 6757.7 4814.4 4875.6 4881.1 4893.7 4897.6 6505.7 6507.2 7093.8 7094.2 7149.0 4530.2 4684.9 4807.2 4979.8 4981.5 4906.8 5059.9 5068.9 5070.9 5075.3 4417.6 4862.2 5370.3 5432.7 5500.5 5797.4 6696.4 6708.3 6719.0 6800.6 1468.5 1719.3 1756.6 1760.5 1765.0 1195.7 1214.2 1216.6 1217.0 1228.4 5182.7 6251.0 6263.2 6267.5 6325.2 6681.3 6907.6 6923.7 6941.4 6975.8 4856.9 5623.6 5693.5 5795.7 6277.2 3238.0 3434.4 3647.8 3656.7 3674.9 6078.7 6883.7 7088.2 7154.7 7178.7 6020.8 6859.7 6884.8 7131.8 7234.7 5830.9 5930.2 6259.9 6310.3 7154.7 5485.7 5756.4 6975.4 7029.9 7068.9 5483.9 6863.0 6915.9 6922.0 6981.6 5954.0 6821.0 7208.6 7215.6 7236.4 4388.3 4840.7 5048.5 5059.6 5089.4 5711.6 5787.4 6683.5 6888.6 7015.0 6344.5 6521.3 6663.1 6674.7 6782.5 6329.6 6646.2 6715.3 6730.9 6741.0 6402.3 6569.9 6592.2 6618.0 6630.3 5224.8 6241.4 6738.0 6763.6 6770.7 5493.5 6360.7 6496.3 6584.3 6585.0 20:49:54 INFO - PID 2191 | 20:49:54 INFO - PID 2191 | [#5] yui.html Cycles:180 Average:24178.48 Median:17414.93 stddev:37367.44 (214.6%) stddev-sans-first:37430.63 20:49:54 INFO - PID 2191 | Values: 684.6 704.5 831.4 847.8 859.0 13511.5 17185.5 17201.0 19117.2 19827.4 15745.2 18110.7 18160.4 18666.0 18666.5 979.0 988.2 997.1 1004.6 1022.2 837.3 981.4 1001.9 1008.3 1009.4 1009.4 1041.7 1072.9 1077.2 1080.8 1052.7 1061.4 1061.9 1066.5 1069.3 702.2 765.9 783.5 784.3 785.5 17457.3 17478.2 17535.3 17673.4 17726.0 16571.8 16851.8 16869.0 16874.4 17563.6 12731.8 14347.4 14428.5 14614.1 14646.6 3243.3 3553.1 3604.8 3638.7 3640.5 127443.4 138064.0 145340.0 147406.0 147892.3 11565.8 13726.5 13763.4 13918.3 13967.3 25885.4 26218.7 26371.0 26383.9 26387.0 7560.8 7696.4 7718.7 7722.7 7734.1 3256.7 3875.8 3895.2 3902.8 3907.0 4395.8 4409.3 4424.9 4490.7 4503.7 735.0 839.2 864.2 864.8 865.0 634.5 665.7 667.4 673.0 689.2 10963.5 11209.7 11994.7 12025.1 12157.8 636.1 646.8 649.5 656.3 665.0 4070.6 4439.4 5561.7 5630.3 5748.3 128756.4 137375.0 146250.0 147188.6 148232.3 106135.5 111852.4 113435.9 113708.0 117394.0 97525.0 100107.5 100269.0 100435.5 101558.0 8971.8 10077.7 11413.3 11498.2 12236.8 36457.6 37269.6 37375.6 37594.0 38006.0 48347.3 48815.8 49003.0 49495.8 49545.5 16661.0 16667.7 16922.7 16953.4 17145.2 16273.8 17464.3 18345.5 18787.0 18826.0 17037.9 17212.5 17305.7 17367.5 17472.1 16800.4 17188.3 17200.6 17220.5 17372.6 13747.2 16620.6 16809.4 17018.0 17121.8 11831.0 12082.0 12135.4 12222.9 12251.8 10910.9 12577.4 13383.4 13502.3 13563.5 20:49:54 INFO - PID 2191 | -------- Summary: end -------- 20:49:54 INFO - PID 2191 | 20:49:55 INFO - TEST-INFO | 2191: exit 0 20:49:55 INFO - TEST-OK | dromaeo_css | took 1141563ms 20:49:55 INFO - TEST-START | kraken 20:49:55 INFO - operating with platform_type : mac_ 20:49:55 INFO - Initialising browser for kraken test... 20:49:55 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49629/getInfo.html -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpv5oVB_/profile 20:49:55 INFO - TEST-INFO | started process 2307 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49629/getInfo.html -foreground) 20:50:28 INFO - TEST-INFO | 2307: killed by SIGTERM 20:50:28 INFO - Browser initialized. 20:50:28 INFO - Running cycle 1/1 for kraken test... 20:50:28 INFO - TEST-INFO | started process 2315 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpv5oVB_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:50:30 INFO - PID 2315 | 2017-05-25 20:50:30.700 plugin-container[2316:18387] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x972f, name = 'com.apple.tsm.portname' 20:50:30 INFO - PID 2315 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:30 INFO - PID 2315 | 2017-05-25 20:50:30.701 plugin-container[2316:18387] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 20:50:30 INFO - PID 2315 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:30 INFO - PID 2315 | 2017-05-25 20:50:30.701 plugin-container[2316:18387] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:50:31 INFO - PID 2315 | 2017-05-25 20:50:31.476 plugin-container[2317:18429] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 20:50:31 INFO - PID 2315 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:31 INFO - PID 2315 | 2017-05-25 20:50:31.477 plugin-container[2317:18429] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 20:50:31 INFO - PID 2315 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:50:31 INFO - PID 2315 | 2017-05-25 20:50:31.477 plugin-container[2317:18429] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:50:40 INFO - PID 2315 | RSS: Main: 243789824 20:50:40 INFO - PID 2315 | 20:50:43 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49629/tests/kraken/driver.html?testName=audio-beat-detection) 20:50:44 INFO - PID 2315 | RSS: Main: 257359872 20:50:44 INFO - PID 2315 | 20:50:46 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49629/tests/kraken/driver.html?testName=audio-dft) 20:50:47 INFO - PID 2315 | RSS: Main: 266219520 20:50:47 INFO - PID 2315 | 20:50:50 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49629/tests/kraken/driver.html?testName=audio-fft) 20:50:51 INFO - PID 2315 | RSS: Main: 269209600 20:50:51 INFO - PID 2315 | 20:50:53 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49629/tests/kraken/driver.html?testName=audio-oscillator) 20:50:53 INFO - PID 2315 | RSS: Main: 270389248 20:50:53 INFO - PID 2315 | 20:50:55 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49629/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:50:56 INFO - PID 2315 | RSS: Main: 270663680 20:50:56 INFO - PID 2315 | 20:50:59 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49629/tests/kraken/driver.html?testName=imaging-darkroom) 20:50:59 INFO - PID 2315 | RSS: Main: 276283392 20:50:59 INFO - PID 2315 | 20:51:03 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49629/tests/kraken/driver.html?testName=imaging-desaturate) 20:51:03 INFO - PID 2315 | RSS: Main: 279068672 20:51:03 INFO - PID 2315 | 20:51:06 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49629/tests/kraken/driver.html?testName=json-parse-financial) 20:51:07 INFO - PID 2315 | RSS: Main: 278282240 20:51:07 INFO - PID 2315 | 20:51:08 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49629/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:51:09 INFO - PID 2315 | RSS: Main: 277958656 20:51:09 INFO - PID 2315 | 20:51:11 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:51:11 INFO - PID 2315 | RSS: Main: 277692416 20:51:11 INFO - PID 2315 | 20:51:13 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:51:14 INFO - PID 2315 | RSS: Main: 277667840 20:51:14 INFO - PID 2315 | 20:51:17 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:51:18 INFO - PID 2315 | RSS: Main: 277417984 20:51:18 INFO - PID 2315 | 20:51:21 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:51:21 INFO - PID 2315 | RSS: Main: 277544960 20:51:21 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | Cycle 1(1): loaded http://localhost:49629/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49629/tests/kraken/driver.html?testName=ai-astar) 20:51:23 INFO - PID 2315 | RSS: Main: 279150592 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | __start_tp_report 20:51:23 INFO - PID 2315 | _x_x_mozilla_page_load 20:51:23 INFO - PID 2315 | _x_x_mozilla_page_load_details 20:51:23 INFO - PID 2315 | |i|pagename|runs| 20:51:23 INFO - PID 2315 | |0;ai-astar;102;117;115;114;114;115;114;114;116;114 20:51:23 INFO - PID 2315 | |1;audio-beat-detection;134;121;111;172;134;112;110;111;138;110 20:51:23 INFO - PID 2315 | |2;audio-dft;196;202;195;200;209;245;279;201;196;194 20:51:23 INFO - PID 2315 | |3;audio-fft;78;58;62;85;58;61;59;59;79;58 20:51:23 INFO - PID 2315 | |4;audio-oscillator;89;70;84;71;78;54;75;56;62;76 20:51:23 INFO - PID 2315 | |5;imaging-gaussian-blur;88;89;88;89;88;91;88;88;88;109 20:51:23 INFO - PID 2315 | |6;imaging-darkroom;105;115;96;95;96;95;136;109;155;152 20:51:23 INFO - PID 2315 | |7;imaging-desaturate;91;96;93;90;91;89;104;90;94;98 20:51:23 INFO - PID 2315 | |8;json-parse-financial;49;47;51;52;53;52;54;52;51;51 20:51:23 INFO - PID 2315 | |9;json-stringify-tinderbox;60;61;56;59;67;71;54;59;57;57 20:51:23 INFO - PID 2315 | |10;stanford-crypto-aes;90;85;88;88;89;96;91;90;122;89 20:51:23 INFO - PID 2315 | |11;stanford-crypto-ccm;138;140;135;139;146;150;163;162;159;159 20:51:23 INFO - PID 2315 | |12;stanford-crypto-pbkdf2;180;183;183;185;183;187;185;185;181;189 20:51:23 INFO - PID 2315 | |13;stanford-crypto-sha256-iterative;78;86;99;95;72;66;65;68;67;70 20:51:23 INFO - PID 2315 | __end_tp_report 20:51:23 INFO - PID 2315 | __start_cc_report 20:51:23 INFO - PID 2315 | _x_x_mozilla_cycle_collect,1049 20:51:23 INFO - PID 2315 | __end_cc_report 20:51:23 INFO - PID 2315 | __startTimestamp1495770683944__endTimestamp 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | ------- Summary: start ------- 20:51:23 INFO - PID 2315 | Number of tests: 14 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.50 stddev:4.17 (3.6%) stddev-sans-first:1.09 20:51:23 INFO - PID 2315 | Values: 102.0 117.0 115.0 114.0 114.0 115.0 114.0 114.0 116.0 114.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:127.50 stddev:19.91 (15.6%) stddev-sans-first:20.86 20:51:23 INFO - PID 2315 | Values: 134.0 121.0 111.0 172.0 134.0 112.0 110.0 111.0 138.0 110.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#2] audio-dft Cycles:10 Average:211.70 Median:201.50 stddev:28.04 (13.9%) stddev-sans-first:29.16 20:51:23 INFO - PID 2315 | Values: 196.0 202.0 195.0 200.0 209.0 245.0 279.0 201.0 196.0 194.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#3] audio-fft Cycles:10 Average:65.70 Median:61.50 stddev:10.56 (17.2%) stddev-sans-first:10.22 20:51:23 INFO - PID 2315 | Values: 78.0 58.0 62.0 85.0 58.0 61.0 59.0 59.0 79.0 58.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#4] audio-oscillator Cycles:10 Average:71.50 Median:75.50 stddev:11.43 (15.1%) stddev-sans-first:10.22 20:51:23 INFO - PID 2315 | Values: 89.0 70.0 84.0 71.0 78.0 54.0 75.0 56.0 62.0 76.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:88.00 stddev:6.54 (7.4%) stddev-sans-first:6.86 20:51:23 INFO - PID 2315 | Values: 88.0 89.0 88.0 89.0 88.0 91.0 88.0 88.0 88.0 109.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#6] imaging-darkroom Cycles:10 Average:115.40 Median:125.00 stddev:23.73 (19.0%) stddev-sans-first:24.87 20:51:23 INFO - PID 2315 | Values: 105.0 115.0 96.0 95.0 96.0 95.0 136.0 109.0 155.0 152.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:92.00 stddev:4.65 (5.1%) stddev-sans-first:4.83 20:51:23 INFO - PID 2315 | Values: 91.0 96.0 93.0 90.0 91.0 89.0 104.0 90.0 94.0 98.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:52.00 stddev:1.99 (3.8%) stddev-sans-first:1.94 20:51:23 INFO - PID 2315 | Values: 49.0 47.0 51.0 52.0 53.0 52.0 54.0 52.0 51.0 51.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:59.50 stddev:5.20 (8.7%) stddev-sans-first:5.51 20:51:23 INFO - PID 2315 | Values: 60.0 61.0 56.0 59.0 67.0 71.0 54.0 59.0 57.0 57.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:89.50 stddev:10.63 (11.9%) stddev-sans-first:11.23 20:51:23 INFO - PID 2315 | Values: 90.0 85.0 88.0 88.0 89.0 96.0 91.0 90.0 122.0 89.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#11] stanford-crypto-ccm Cycles:10 Average:149.10 Median:154.50 stddev:10.92 (7.1%) stddev-sans-first:10.82 20:51:23 INFO - PID 2315 | Values: 138.0 140.0 135.0 139.0 146.0 150.0 163.0 162.0 159.0 159.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:185.00 stddev:2.69 (1.5%) stddev-sans-first:2.40 20:51:23 INFO - PID 2315 | Values: 180.0 183.0 183.0 185.0 183.0 187.0 185.0 185.0 181.0 189.0 20:51:23 INFO - PID 2315 | 20:51:23 INFO - PID 2315 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:75.00 stddev:12.51 (16.7%) stddev-sans-first:13.26 20:51:23 INFO - PID 2315 | Values: 78.0 86.0 99.0 95.0 72.0 66.0 65.0 68.0 67.0 70.0 20:51:23 INFO - PID 2315 | -------- Summary: end -------- 20:51:23 INFO - PID 2315 | 20:51:24 INFO - TEST-INFO | 2315: exit 0 20:51:24 INFO - TEST-OK | kraken | took 89570ms 20:51:24 INFO - SUITE-END | took 1231s 20:51:25 INFO - Completed test suite (00:20:31) 20:51:25 INFO - javascript benchmark 20:51:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7887.763960757166, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3068.8465576721164, 3557.8576856925724, 3584.677379035886, 3602.0, 3603.9459408108874, 6101.489633126343, 6169.598976066558, 6218.937810621893, 6221.720022598983, 6224.657643829588, 5046.99060187962, 5287.603429742775, 6120.020512922639, 6134.008014331981, 6240.095186197998, 5459.972700136514, 5468.808591699291, 5472.480114389146, 5484.204790305404, 5615.719214039282, 4975.875603109933, 5521.420250873661, 5575.944240557583, 5613.158026296068, 5641.464060914206, 5345.545628621572, 5427.810026649087, 5491.450854914477, 5523.199136125239, 5560.7219639018085, 5442.537384322337, 5448.210009548593, 5478.945210547883, 5485.369182544018, 5513.173024046385, 5417.295751552312, 5420.756065977041, 5436.157395603688, 5450.754716037788, 5460.34475862899, 6123.969380153071, 6237.563370564062, 6239.625622462667, 6241.875162496724, 6245.968770156165, 6028.0, 6575.243846957614, 6584.078229047937, 6710.93289067108, 6711.6979735912, 6806.251312355637, 6821.0, 6828.965855170742, 6832.0, 6864.519483636147, 6296.905546416808, 6321.65230912295, 6322.525810564226, 6323.52573556985, 6326.335734747877, 6695.0, 6778.254392016875, 6847.828804279933, 6887.724491020404, 6919.930800692029, 5313.282706834605, 5589.692566908859, 6310.305866354697, 6437.452816510556, 6591.110200123018, 6672.766453174116, 6723.0, 6742.49431292655, 6743.831404214836, 6765.763198288135, 6026.523501742014, 6467.029945508118, 6532.0, 6563.737450502022, 6565.770198043141, 6480.449161821298, 6546.672666366662, 6556.114924485229, 6591.6374599397495, 6618.205815302098, 4745.311929770164, 5323.733813309319, 5364.088105022156, 5366.7853285868905, 5399.892002159935, 4772.618190544748, 4775.546323099369, 4793.664443488992, 4795.472498025214, 4823.5658790709, 6207.441330280297, 6240.594361366497, 6243.906341404882, 6299.96850015747, 6309.400606942333, 6779.186497620317, 6822.692978815965, 6833.58998460094, 6839.794806155824, 6858.6570671466225, 5786.710664466844, 5890.9410905891245, 6247.0, 6284.088807122942, 6322.304546499881, 5778.913316300259, 5802.0, 5856.297244330623, 6007.948608993409, 6020.638761674226, 6871.896921546081, 6934.445244380337, 6975.441964642715, 6983.336583024605, 7023.719051238098, 6843.076184715199, 6845.315468453115, 6848.7260509579055, 6851.246362900177, 6891.241963383924, 6709.7316107355155, 6778.0, 6929.930700692928, 6935.930640693528, 6962.23415424313, 5498.615096943336, 6540.640264785482, 6558.344165583403, 6646.468282537482, 6720.563163394364, 6824.863502729819, 6840.897386539106, 6881.759138430331, 6883.414909732729, 6904.965475172592, 6352.205974253218, 6431.067495213074, 6491.4482269005775, 6538.378854008862, 6638.83402914931, 6547.821392149459, 6595.340465953366, 6596.142501474969, 6623.635700036544, 6630.30381809903, 6384.7446102157255, 6469.061986012096, 6478.773242936474, 6485.254195767548, 6489.9351006491215, 6404.455621272244, 6627.900581491185, 6634.668266586747, 6636.83407914806, 6653.900191497035, 5393.732472868951, 6298.811035668938, 6399.296077431572, 6486.318936511599, 6487.772927947499], "value": 6090.964649329484, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.3829999800188, 771.8301973565814, 827.8923739913811, 1088.9727756806085, 1215.434822807395, 39128.804355978216, 40154.59845401549, 40160.79919600405, 40179.0, 40317.99205019872, 39950.40074398887, 40042.399364009565, 40368.38526458938, 40394.39408408876, 40457.39313910294, 7845.0, 8009.719659811908, 8343.207395297437, 8527.104654011333, 8538.188872057144, 8407.24334809867, 8506.489610623383, 8511.404201705884, 8520.74437766868, 8608.52653104079, 8368.832623347529, 8432.662693492253, 8501.277391421736, 8524.147585241457, 8554.828903421927, 8422.452540584904, 8434.83130337396, 8458.619362128687, 8487.872681909776, 8503.659853605848, 6652.833679158011, 8167.346612271063, 8233.217844304781, 8253.463524870864, 8326.417150799447, 37336.69321573759, 37543.0, 37723.811380943196, 37875.43186852199, 37970.430443543366, 3546.131197856516, 3583.802890841003, 3584.551931008624, 3590.2460483298537, 3595.1551385424405, 30326.241843953856, 30629.540556891665, 30687.079387618407, 30697.69302306993, 30761.230969225726, 3068.2636167319906, 3081.568580398746, 3095.876164953399, 3099.798513096664, 3110.626724793039, 231134.0, 233516.66483335118, 265409.67295163596, 275390.0, 275994.0, 47161.5283847164, 47281.290780638315, 47409.051818964115, 47553.0, 47676.761616191696, 49304.260436093486, 49461.50538494641, 49529.0, 49549.25676114861, 49649.75175124101, 11769.823452648217, 13987.860121398655, 14018.439262429389, 14152.221627810364, 14156.221407822462, 11583.942080289544, 11608.709782255512, 11628.953394194563, 11643.941780291045, 11655.825162622568, 13537.526186583422, 13569.1858488491, 13586.932065339808, 13599.184048957095, 13695.383707733175, 5029.0, 5536.169574563767, 6042.093685947143, 6086.969565152146, 6092.0, 968.5399435268332, 973.5716284834791, 990.8513722941616, 1001.0490034467285, 1009.8737657792776, 22561.774382255968, 24256.878715606308, 24478.265652030466, 24502.26493205207, 24657.753422465903, 1094.895984881435, 1120.2102517724984, 1121.775644871029, 1153.5385845661635, 1156.7166044319197, 11971.52113915451, 12598.748025039631, 12647.620571382873, 12672.429740661692, 12681.492740290285, 301811.0, 302838.0, 302937.0, 302945.0, 303472.0, 43254.783726081165, 52911.0, 53165.468345316054, 53298.0, 53438.73280633572, 1475.3877140986585, 1502.136271643809, 1527.3127092808058, 1530.6632540840997, 1534.110216074674, 23883.641745373487, 24072.638910416706, 24134.03463861426, 24181.63727544053, 24234.515309693354, 37723.245535088594, 37822.0, 37849.81075094607, 37884.86345409532, 37928.0, 210321.0, 254178.0, 258663.0, 259109.70445148408, 260162.0, 33599.49600755942, 34026.829865850515, 34189.65810341964, 34842.65157348394, 34887.47668785021, 37326.440103399014, 43404.0, 45169.0, 45588.088238235716, 45741.771291143334, 26361.604575930993, 27856.0, 29901.401971960004, 31318.530222047142, 31377.05868823939, 23515.88242058847, 30859.84570077135, 31101.844490777403, 31120.0, 31242.21894452566, 35963.28073438464, 37088.81455592705, 37122.8143859279, 37145.071373215884, 37587.62412375951, 27586.620668966872, 27923.44450221579, 31276.218094546908, 31297.530537041508, 31535.89624363228, 23610.88194559016, 24114.75885241125, 24387.39031524226, 24530.754692452847, 24639.753602463745], "value": 17269.679915510318, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.90586071064376, 432.4162380785941, 440.19224722633965, 451.8418553506271, 457.1976181800939, 20442.795572044273, 20535.897320513377, 21186.152553897846, 21947.780522194815, 21992.78007219927, 19880.10539525721, 22546.774532254673, 22871.656925146093, 23175.420614484647, 23823.166189183383, 6000.099985002263, 6220.564560480768, 6223.564350495467, 6228.034654628517, 6250.781222657208, 5957.553183511232, 6087.452129308362, 6109.938900610981, 6130.570860039799, 6220.502359811204, 5340.332458442695, 5762.616971926728, 6046.879062418749, 6077.848053798668, 6098.939010609904, 5147.871303217431, 5975.846786483396, 6031.668258245794, 6058.0, 6071.089336599502, 5404.675719456845, 5410.269613602192, 5410.97294513525, 5413.188021796723, 5436.238926550286, 27233.591496127574, 27588.862055689595, 27881.86059069712, 28011.299717507023, 28130.0, 2152.0638522242875, 2164.5562659654734, 2177.433867194525, 2178.9346319610436, 2182.0289970962926, 17307.653846923116, 17514.737278940825, 17708.72164979199, 17908.194131263994, 17962.281508739634, 2133.210712036541, 2139.2833400810746, 2171.0881429799524, 2185.7923497267893, 2188.004457971619, 128907.35546322302, 151568.0, 153761.0, 155595.0, 157579.0, 12801.743965120831, 12843.48626054966, 12903.419346129447, 12905.096643234883, 12906.483740650268, 16665.25006374716, 16711.91644041772, 16724.414645487348, 16731.247093880804, 16754.99470031802, 8797.420445720276, 9116.316276279305, 9118.221946720458, 9212.170904618617, 9254.213391861633, 6617.8014659560295, 7664.501807382502, 7847.450678452454, 8637.740867773977, 8702.434341767765, 7993.440459167919, 8142.715004974797, 8171.305439037748, 8184.836303273901, 8197.50814951093, 2189.6168170570086, 2199.3621849663737, 2207.4812419081504, 2217.5786600545844, 2365.562370961378, 904.6562306323555, 930.6417029443578, 946.8248374050685, 962.6438217859437, 966.5215718219526, 17857.28570857151, 21150.576988460452, 21411.715297082224, 21440.24959126423, 21767.34697959064, 993.145894530703, 1000.4493035794635, 1010.939343639384, 1018.5772904244656, 1030.7629245273629, 10463.84304235437, 11109.888901110886, 11136.220464567565, 11244.718882028015, 11266.774664506664, 506758.0, 515055.0, 515091.0, 524367.0, 525965.0, 118054.8194518061, 118494.8150518501, 122373.04575202584, 127910.0, 128424.36817238745, 1046.9476526173812, 1214.368528365245, 1241.7392347607104, 1244.9066320025854, 1249.3690686203408, 22534.32397028092, 22622.832803407207, 22890.656640150744, 22969.88515057481, 23142.305730828102, 58327.87557306592, 58542.707286463294, 58932.11601826062, 59012.0, 59213.703931480064, 77605.0, 77670.6116469414, 78162.6091869537, 79362.0, 79542.6022869882, 9059.411138275991, 9081.228095611947, 9174.220191283815, 9200.631974721204, 9346.719598412059, 13185.53850615224, 14081.366338514586, 14315.427382904567, 14515.637109072357, 14546.272686365852, 10145.391276523433, 10784.568617255221, 11151.386673733023, 11216.158788090763, 11596.94201528987, 11140.275882067639, 11169.050630696482, 11415.828762568402, 11477.024452921596, 11666.941665291619, 11549.48027338755, 11711.707207319885, 11852.703682407664, 11966.521339146337, 12008.939955300517, 7465.552066876005, 9016.909830901606, 9069.274458043472, 9168.862467063132, 9274.907250927405, 9374.343795934492, 11491.712707182387, 11777.116716246144, 11787.469563869476, 11884.405779711153], "value": 10444.85795388201, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.5654368873804, 1435.5047508609525, 1440.5030264558723, 1444.0830072903702, 1456.7305048566016, 11659.358735269558, 11671.883281167185, 11787.70530736732, 12114.091443141766, 12202.267863928171, 9841.753956151082, 11837.0, 11946.581869634565, 12117.515299388037, 12151.939240303785, 2454.04292325993, 2484.714257860342, 2499.7750202481775, 2503.8748062596887, 2510.6108553174286, 2134.5197330600568, 2487.465194983078, 2520.218732193017, 2548.5157820014138, 2555.6038813983864, 2088.457001179697, 2469.493753780471, 2491.6885389326335, 2522.155078048856, 2523.6466894634764, 2425.5678711665573, 2438.6463962725484, 2442.8534287942693, 2444.816638752083, 2448.5592593333195, 2432.19737486629, 2432.3311089450544, 2447.8286519943613, 2534.822562420631, 2539.9365015874564, 11723.824142637868, 11787.292762434196, 11839.230450020721, 11898.821517677155, 11942.223755455867, 6257.311695713468, 6441.645709485976, 6454.1609590752905, 6459.935400645981, 6478.222613286389, 3924.2347742190286, 4076.7961601919787, 4141.668666506672, 4150.605692459242, 4167.874963751093, 1189.6847335456112, 1214.0, 1220.6216073017392, 1221.1940119521073, 1234.0251201550764, 15324.463643772526, 15723.13522756248, 16427.178641068018, 16563.83436165635, 16581.91709041459, 5316.202569614511, 5355.196720491879, 5546.334439867242, 5556.944430555724, 5587.1339942309005, 11759.647210583526, 11884.40577971098, 11886.108541859396, 11899.643010709693, 11909.344986025679, 6234.189555357774, 6311.842203944938, 6339.904901426482, 6348.682565871688, 6353.491720662336, 5865.149553314745, 5894.38108998549, 5899.380565040609, 6019.157317975487, 6109.84725381869, 4290.334998075304, 4322.37325587788, 4335.2196604610845, 4349.543297953733, 4435.534268901783, 1251.743392604514, 1273.1215461331838, 1275.5089290623177, 1286.6783304173957, 1289.2522337044493, 794.4983980277281, 795.9641816118288, 820.6471217376466, 822.5352675738188, 843.843888880559, 6116.48009919153, 6272.8118156455375, 6330.303666596671, 6365.936340636627, 6428.6785660717715, 586.865021045162, 602.8733965867129, 606.3300053440972, 608.178958406157, 613.2916481463916, 3412.805518068656, 3647.6321379482692, 3875.457435958968, 3894.9221015580097, 4450.332450132441, 13957.232352220726, 14563.271836408137, 14695.0, 14922.477713280201, 14967.2787629424, 14266.215358155401, 14541.98206125561, 14575.781363279559, 14781.334839932013, 14859.777103343458, 8212.671493140208, 8361.665533378718, 8456.450330728483, 8461.196186362286, 8514.552526070584, 5372.650777699437, 6561.737530498726, 6642.202935647753, 6791.185057793096, 6826.761063362758, 9654.0, 12894.935525322313, 12979.221246725227, 13168.670783230113, 13311.068225224528, 13318.0, 13353.265570393332, 13448.529301474402, 13598.0, 13656.93171534136, 8108.189181082081, 8178.509289442414, 8276.048254450816, 8300.916990830014, 8309.459885107448, 9208.907910921072, 10404.167666586503, 10457.633982810565, 10583.259171858204, 10594.947025264824, 7527.3601743850195, 8872.64509419616, 9124.27005839518, 9174.403663761834, 9191.632334706537, 8687.174718401742, 8758.03661597211, 8783.72635967794, 8870.068642792414, 9049.77375565616, 7345.75122229233, 7676.270754278335, 8045.396595255263, 8063.35493160534, 8141.348692104736, 5976.701164941823, 6031.336552979081, 6071.0, 6075.27096748393, 6223.719932603134, 6033.155358249936, 6066.059760737093, 6119.816405507842, 6140.078988151634, 6229.750809967551], "value": 5210.581065842665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.1998722860792, 399.4387885021546, 400.0977795072112, 400.399400898652, 407.9632833045025, 5991.970040149804, 6308.779192728255, 6343.714532846021, 6352.0, 6359.650219237946, 4245.681573881956, 6203.844903877405, 6219.9689001554925, 6251.09359142924, 6286.151369565118, 4528.252838281696, 4753.738544380058, 4774.73738944358, 4786.83246086387, 4796.83211087612, 4696.624270058404, 4810.278458231258, 4813.133635945528, 4823.372961514998, 4834.661573689843, 4763.14263432582, 4771.90456190876, 4778.331033655291, 4784.760761961906, 4792.257200133985, 4713.976430117862, 4762.785674644615, 4787.760611969423, 4803.303520989454, 4811.663183577133, 4192.706510544263, 4240.406343111967, 4246.745195288293, 4257.489101307832, 4272.2950713132295, 5201.323827902348, 5541.27963364764, 5834.154047663065, 5962.612430192024, 6198.287196972361, 5037.823676171316, 5696.259486266758, 5696.914546281809, 5713.857153571153, 5726.971365143189, 6083.48290395317, 6613.503987200931, 6722.630255335955, 6754.898676519856, 6757.662116894185, 4814.374131362901, 4875.609951203895, 4881.1213981483315, 4893.730844803535, 4897.583705385011, 6505.739770409178, 6507.154069970874, 7093.772333588205, 7094.219635840053, 7149.034015645739, 4530.22986092365, 4684.929726054112, 4807.158747219222, 4979.833772309057, 4981.526754958273, 4906.779194936165, 5059.87350316245, 5068.949310506848, 5070.949290507047, 5075.3148324975655, 4417.60241578253, 4862.17343051682, 5370.301860758076, 5432.674039557639, 5500.532454741392, 5797.449242321973, 6696.408498903373, 6708.2956289589865, 6718.966405168041, 6800.591964482147, 1468.4933697874217, 1719.3208682570314, 1756.5959829239346, 1760.4718584424622, 1764.97352539712, 1195.6950977500856, 1214.1925619463134, 1216.647172320035, 1216.983818511535, 1228.416502161466, 5182.689038657617, 6251.0, 6263.185785847902, 6267.467265282411, 6325.240971083499, 6681.298463661343, 6907.585544867224, 6923.653817309114, 6941.375276225063, 6975.79072627822, 4856.927146092811, 5623.550115990711, 5693.544516438675, 5795.710214489259, 6277.246730392382, 3238.0, 3434.3989801784587, 3647.7962272450027, 3656.6526180012856, 3674.9053039767077, 6078.696065196811, 6883.690233939485, 7088.220295767462, 7154.678039488235, 7178.748743793943, 6020.759169633166, 6859.691313890787, 6884.793456196322, 7131.82170445743, 7234.710611575478, 5830.892130495193, 5930.22907022093, 6259.873222819936, 6310.274318453345, 7154.678039488235, 5485.725713714378, 5756.395578464201, 6975.372216500539, 7029.894551581628, 7068.929310706827, 5483.917741233964, 6863.039174515671, 6915.861682766417, 6921.988934813372, 6981.603679264269, 5954.0, 6821.0, 7208.639568021683, 7215.6031418270395, 7236.444164504656, 4388.3105004572835, 4840.709557426425, 5048.5203905628905, 5059.6458247923765, 5089.389273287229, 5711.600187986801, 5787.421257874179, 6683.498737594603, 6888.552244104118, 7015.018617087615, 6344.492440604648, 6521.315261897622, 6663.067170596218, 6674.6662666865495, 6782.525223234326, 6329.620222786648, 6646.2356828965285, 6715.328467153246, 6730.899036514358, 6740.966295168493, 6402.263739669997, 6569.9014514783275, 6592.17597800275, 6618.007298905202, 6630.3038180992235, 5224.817131400383, 6241.375862413904, 6738.0, 6763.560368576027, 6770.695318710768, 5493.478119578634, 6360.713767880364, 6496.329573790793, 6584.341565843569, 6585.045168450643], "value": 4944.564662697369, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.5624189202672, 704.535006895449, 831.3515457942802, 847.7838151271425, 858.9742307730768, 13511.459541618337, 17185.484435466926, 17201.0, 19117.23531058756, 19827.405177844652, 15745.212739363014, 18110.728339074893, 18160.36438724645, 18666.0, 18666.53333666659, 978.990210097897, 988.2341185581196, 997.0677416615451, 1004.6031817432109, 1022.2486472442748, 837.3217693668117, 981.3670182732134, 1001.8647482589867, 1008.2841182760212, 1009.3540134314045, 1009.3540134314045, 1041.671873359889, 1072.8766191887917, 1077.1974878715369, 1080.8054550180964, 1052.726291164299, 1061.3525749292926, 1061.9309744866598, 1066.5040756048397, 1069.315637991686, 702.2345643248832, 765.9157492675802, 783.4907310248327, 784.3176436500281, 785.4815821557744, 17457.301707931667, 17478.213480393286, 17535.298588056463, 17673.381431649832, 17726.0, 16571.83428165715, 16851.831481685273, 16868.987860728397, 16874.409395671093, 17563.64872702539, 12731.809022864665, 14347.42610295587, 14428.495002674961, 14614.123152610877, 14646.63383415423, 3243.3189030303665, 3553.093961039944, 3604.819759012039, 3638.745287829853, 3640.4539319102346, 127443.36278318641, 138064.0, 145340.0, 147406.0, 147892.2605386977, 11565.76868462626, 13726.519571814939, 13763.449462021606, 13918.30408479572, 13967.301634918213, 25885.352866178495, 26218.737812621628, 26371.0, 26383.868080659475, 26387.0, 7560.773176804704, 7696.4227682924, 7718.691252349843, 7722.652480638385, 7734.110577283572, 3256.723178529804, 3875.8255878485534, 3895.220955808793, 3902.824372903226, 3906.9609303907164, 4395.780210989437, 4409.272470042407, 4424.867253982386, 4490.685652004394, 4503.707259028218, 734.9816254593678, 839.1524560194126, 864.1660797330607, 864.8400045991386, 864.9740507784777, 634.5082561015158, 665.6605131382933, 667.3926726678699, 672.9562578432386, 689.1798759476308, 10963.451827408599, 11209.71975700614, 11994.700132496757, 12025.098117641212, 12157.817632735516, 636.0999186151636, 646.7833275852548, 649.4966401039233, 656.2584279763931, 665.0456594786497, 4070.592940705965, 4439.400680908101, 5561.6663000220115, 5630.324361076615, 5748.281464816898, 128756.35621821831, 137375.0, 146250.0, 147188.5843424699, 148232.2588387051, 106135.46932265443, 111852.44073779578, 113435.86564134253, 113708.0, 117394.0, 97525.04949901105, 100107.49946250368, 100269.0, 100435.49782251188, 101558.0, 8971.775705607412, 10077.69766906994, 11413.315201087962, 11498.195126341076, 12236.75526489483, 36457.63542364542, 37269.6273037277, 37375.62624373721, 37594.0, 38006.0, 48347.27479087746, 48815.75592122017, 49003.01993960029, 49495.75252123716, 49545.50454495409, 16661.0, 16667.74998375001, 16922.661546768748, 16953.406630767862, 17145.228464718868, 16273.75589366184, 17464.340887615657, 18345.54136146607, 18786.96671683016, 18826.0, 17037.914810425867, 17212.483625491255, 17305.65388692194, 17367.47897563075, 17472.12639368001, 16800.411985580446, 17188.31246750116, 17200.569985749957, 17220.543806646147, 17372.565685857953, 13747.175169489863, 16620.58448538796, 16809.41167059147, 17018.0, 17121.82878171252, 11831.0, 12082.039900224501, 12135.393230338625, 12222.87777122253, 12251.816222756488, 10910.854360292054, 12577.371131443575, 13383.39774710121, 13502.257375844423, 13563.525276615272], "value": 8507.587368502693, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 116.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 121.0, 111.0, 172.0, 134.0, 112.0, 110.0, 111.0, 138.0, 110.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 195.0, 200.0, 209.0, 245.0, 279.0, 201.0, 196.0, 194.0], "value": 211.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 62.0, 85.0, 58.0, 61.0, 59.0, 59.0, 79.0, 58.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 84.0, 71.0, 78.0, 54.0, 75.0, 56.0, 62.0, 76.0], "value": 71.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 89.0, 88.0, 91.0, 88.0, 88.0, 88.0, 109.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 96.0, 95.0, 96.0, 95.0, 136.0, 109.0, 155.0, 152.0], "value": 115.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 93.0, 90.0, 91.0, 89.0, 104.0, 90.0, 94.0, 98.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 51.0, 52.0, 53.0, 52.0, 54.0, 52.0, 51.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 56.0, 59.0, 67.0, 71.0, 54.0, 59.0, 57.0, 57.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 88.0, 88.0, 89.0, 96.0, 91.0, 90.0, 122.0, 89.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 135.0, 139.0, 146.0, 150.0, 163.0, 162.0, 159.0, 159.0], "value": 149.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 183.0, 185.0, 183.0, 187.0, 185.0, 185.0, 181.0, 189.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 99.0, 95.0, 72.0, 66.0, 65.0, 68.0, 67.0, 70.0], "value": 76.6, "unit": "score"}]}]} 20:51:25 INFO - Return code: 0 20:51:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:51:25 INFO - # TBPL SUCCESS # 20:51:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:51:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:51:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:51:25 INFO - 2017-05-25 20:51:25.238 screenresolution[2327:18814] starting screenresolution argv=screenresolution get 20:51:25 INFO - 2017-05-25 20:51:25.241 screenresolution[2327:18814] Display 0: 1600x1200x32@60 20:51:25 INFO - 2017-05-25 20:51:25.247 screenresolution[2328:18817] starting screenresolution argv=screenresolution list 20:51:25 INFO - Available Modes on Display 0 20:51:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:51:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:51:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:51:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:51:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:51:25 INFO - Intel Iris: 20:51:25 INFO - Chipset Model: Intel Iris 20:51:25 INFO - Type: GPU 20:51:25 INFO - Bus: Built-In 20:51:25 INFO - VRAM (Dynamic, Max): 1536 MB 20:51:25 INFO - Vendor: Intel (0x8086) 20:51:25 INFO - Device ID: 0x0a2e 20:51:25 INFO - Revision ID: 0x0009 20:51:25 INFO - Displays: 20:51:25 INFO - Display: 20:51:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:51:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:51:25 INFO - Display Serial Number: 1600x1200 60 20:51:25 INFO - Main Display: Yes 20:51:25 INFO - Mirror: Off 20:51:25 INFO - Online: Yes 20:51:25 INFO - Rotation: Supported 20:51:25 INFO - Return code: 0 20:51:25 INFO - Running post-action listener: _resource_record_post_action 20:51:25 INFO - [mozharness: 2017-05-26 03:51:25.484956Z] Finished run-tests step (success) 20:51:25 INFO - Running post-run listener: _resource_record_post_run 20:51:25 INFO - Total resource usage - Wall time: 1253s; CPU: 53.0%; Read bytes: 348160; Write bytes: 568341504; Read time: 7; Write time: 1824 20:51:25 INFO - TinderboxPrint: CPU usage
53.3% 20:51:25 INFO - TinderboxPrint: I/O read bytes / time
348,160 / 7 20:51:25 INFO - TinderboxPrint: I/O write bytes / time
568,341,504 / 1,824 20:51:25 INFO - TinderboxPrint: CPU idle
2,339.2 (46.7%) 20:51:25 INFO - TinderboxPrint: CPU system
1,018.5 (20.3%) 20:51:25 INFO - TinderboxPrint: CPU user
1,655.9 (33.0%) 20:51:25 INFO - TinderboxPrint: Swap in / out
244,248,576 / 0 20:51:25 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 237072896; Write bytes: 233519104; Read time: 17114; Write time: 510 20:51:25 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 348160; Write bytes: 318528512; Read time: 7; Write time: 1267 20:51:25 INFO - Running post-run listener: _upload_blobber_files 20:51:25 INFO - Blob upload gear active. 20:51:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:51: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 20:51: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'] 20:51: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 20:51:26 INFO - (blobuploader) - INFO - Open directory for files ... 20:51:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:51:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:51:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:27 INFO - (blobuploader) - INFO - Done attempting. 20:51:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:51:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:51:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:28 INFO - (blobuploader) - INFO - Done attempting. 20:51:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:51:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:51:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:29 INFO - (blobuploader) - INFO - Done attempting. 20:51:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:51:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:51:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:51:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:51:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:51:31 INFO - (blobuploader) - INFO - Done attempting. 20:51:31 INFO - (blobuploader) - INFO - Iteration through files over. 20:51:31 INFO - Return code: 0 20:51:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:51:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:51:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/700229cc1b42db7e5e190a8d7a664d00b111bcefbf9f23434435e080742a1ff86e834be9988b86a8cfe30d9b579906eb4cdc110144b4303929f48f94db2f93f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b8719ad10f5fb75bff7f0ca5b19e0f5e5af676ec75fc2381b484cc48ca002fdc7ad5f61b32d034489ab851667659db3bb765d2e661ef19254325b6d3b8b3bb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f7e174f147032119c2aa4f17082417966e59d584440f179080cd4bf89ee8e3187071796ac69bc96d1e6a70006f56f7d7b7508f7b0870beff920e74e01911f6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edce5cd732f793d2b4b6efbc2e7d4fdeb74f791001b82192f1888e7a87ce67ed5a2998fbd4085e8944980750225037b7e32230b5b1eabee2f4cffad9f4805a1"} 20:51:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:51:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:51:31 INFO - Contents: 20:51:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/700229cc1b42db7e5e190a8d7a664d00b111bcefbf9f23434435e080742a1ff86e834be9988b86a8cfe30d9b579906eb4cdc110144b4303929f48f94db2f93f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b8719ad10f5fb75bff7f0ca5b19e0f5e5af676ec75fc2381b484cc48ca002fdc7ad5f61b32d034489ab851667659db3bb765d2e661ef19254325b6d3b8b3bb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f7e174f147032119c2aa4f17082417966e59d584440f179080cd4bf89ee8e3187071796ac69bc96d1e6a70006f56f7d7b7508f7b0870beff920e74e01911f6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edce5cd732f793d2b4b6efbc2e7d4fdeb74f791001b82192f1888e7a87ce67ed5a2998fbd4085e8944980750225037b7e32230b5b1eabee2f4cffad9f4805a1"} 20:51:31 INFO - Running post-run listener: copy_logs_to_upload_dir 20:51:31 INFO - Copying logs to upload dir... 20:51:31 INFO - mkdir: /builds/slave/test/build/upload/logs 20:51:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.173815 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-25 20:51:31.396281) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 20:51:31.399782) ========= 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.GY4UC5oWFu/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.RmverSR5Md/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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/700229cc1b42db7e5e190a8d7a664d00b111bcefbf9f23434435e080742a1ff86e834be9988b86a8cfe30d9b579906eb4cdc110144b4303929f48f94db2f93f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b8719ad10f5fb75bff7f0ca5b19e0f5e5af676ec75fc2381b484cc48ca002fdc7ad5f61b32d034489ab851667659db3bb765d2e661ef19254325b6d3b8b3bb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f7e174f147032119c2aa4f17082417966e59d584440f179080cd4bf89ee8e3187071796ac69bc96d1e6a70006f56f7d7b7508f7b0870beff920e74e01911f6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edce5cd732f793d2b4b6efbc2e7d4fdeb74f791001b82192f1888e7a87ce67ed5a2998fbd4085e8944980750225037b7e32230b5b1eabee2f4cffad9f4805a1"} build_url:https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/BZla1udOSeSHNeV7xcTmCw/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/700229cc1b42db7e5e190a8d7a664d00b111bcefbf9f23434435e080742a1ff86e834be9988b86a8cfe30d9b579906eb4cdc110144b4303929f48f94db2f93f8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b8719ad10f5fb75bff7f0ca5b19e0f5e5af676ec75fc2381b484cc48ca002fdc7ad5f61b32d034489ab851667659db3bb765d2e661ef19254325b6d3b8b3bb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f7e174f147032119c2aa4f17082417966e59d584440f179080cd4bf89ee8e3187071796ac69bc96d1e6a70006f56f7d7b7508f7b0870beff920e74e01911f6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edce5cd732f793d2b4b6efbc2e7d4fdeb74f791001b82192f1888e7a87ce67ed5a2998fbd4085e8944980750225037b7e32230b5b1eabee2f4cffad9f4805a1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 20:51:31.435076) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:51:31.435440) ========= 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.GY4UC5oWFu/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.RmverSR5Md/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005198 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 20:51:31.486849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 20:51:31.487589) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 20:51:31.488320) ========= ========= Total master_lag: 0.19 =========