builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0020 starttime: 1496682997.28 results: success (0) revision: 2f77e11bf03d535873759601f73e2e65f464ba26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.282630) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.283086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.283372) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.308222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.308560) ========= 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.WFpl6xj28m/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.i46JiFL1hm/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-06-05 10:16:37-- 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% 22.5M=0.001s 2017-06-05 10:16:37 (22.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127484 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.459582) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.459929) ========= 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.WFpl6xj28m/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.i46JiFL1hm/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.028083 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:37.510413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 10:16:37.510808) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2f77e11bf03d535873759601f73e2e65f464ba26 --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 2f77e11bf03d535873759601f73e2e65f464ba26 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WFpl6xj28m/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.i46JiFL1hm/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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-06-05 10:16:37,605 truncating revision to first 12 chars 2017-06-05 10:16:37,605 Setting DEBUG logging. 2017-06-05 10:16:37,606 attempt 1/10 2017-06-05 10:16:37,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2f77e11bf03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 10:16:38,950 unpacking tar archive at: mozilla-central-2f77e11bf03d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.624472 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 10:16:39.157271) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:39.157615) ========= script_repo_revision: 2f77e11bf03d535873759601f73e2e65f464ba26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:39.157991) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:39.158262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 10:16:39.177137) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-05 10:16:39.177542) ========= /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.WFpl6xj28m/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.i46JiFL1hm/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:16:39 INFO - MultiFileLogger online at 20170605 10:16:39 in /builds/slave/test 10:16:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:16:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:16:39 INFO - {'append_to_log': False, 10:16:39 INFO - 'base_work_dir': '/builds/slave/test', 10:16:39 INFO - 'blob_upload_branch': 'Firefox', 10:16:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:16:39 INFO - 'branch': 'Firefox', 10:16:39 INFO - 'buildbot_json_path': 'buildprops.json', 10:16:39 INFO - 'config_files': ('talos/mac_config.py',), 10:16:39 INFO - 'default_actions': ('clobber', 10:16:39 INFO - 'read-buildbot-config', 10:16:39 INFO - 'download-and-extract', 10:16:39 INFO - 'populate-webroot', 10:16:39 INFO - 'create-virtualenv', 10:16:39 INFO - 'install', 10:16:39 INFO - 'setup-mitmproxy', 10:16:39 INFO - 'run-tests'), 10:16:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:16:39 INFO - 'download_minidump_stackwalk': True, 10:16:39 INFO - 'download_symbols': 'ondemand', 10:16:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:16:39 INFO - 'tooltool.py': '/tools/tooltool.py', 10:16:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:16:39 INFO - '/tools/misc-python/virtualenv.py')}, 10:16:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:16:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:16:39 INFO - 'gecko_profile': False, 10:16:39 INFO - 'gecko_profile_interval': 0, 10:16:39 INFO - 'installer_path': 'installer.exe', 10:16:39 INFO - 'log_level': 'info', 10:16:39 INFO - 'log_name': 'talos', 10:16:39 INFO - 'log_to_console': True, 10:16:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:16:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:16:39 INFO - 'opt_config_files': (), 10:16:39 INFO - 'pip_index': False, 10:16:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:16:39 INFO - 'cmd': ('bash', 10:16:39 INFO - '-c', 10:16:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:16:39 INFO - 'enabled': True, 10:16:39 INFO - 'halt_on_failure': False, 10:16:39 INFO - 'name': 'check_screen_resolution'},), 10:16:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:16:39 INFO - 'cmd': ('bash', 10:16:39 INFO - '-c', 10:16:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:16:39 INFO - 'enabled': True, 10:16:39 INFO - 'halt_on_failure': False, 10:16:39 INFO - 'name': 'check_screen_resolution'},), 10:16:39 INFO - 'run_cmd_checks_enabled': True, 10:16:39 INFO - 'suite': 'dromaeojs-e10s', 10:16:39 INFO - 'system_bits': '32', 10:16:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:16:39 INFO - 'title': 't-yosemite-r7-0020', 10:16:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:16:39 INFO - 'use_talos_json': True, 10:16:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:16:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:16:39 INFO - 'work_dir': 'build'} 10:16:39 INFO - [mozharness: 2017-06-05 17:16:39.388531Z] Running clobber step. 10:16:39 INFO - Running pre-action listener: _resource_record_pre_action 10:16:39 INFO - Running main action method: clobber 10:16:39 INFO - rmtree: /builds/slave/test/build 10:16:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:16:41 INFO - Running post-action listener: _resource_record_post_action 10:16:41 INFO - [mozharness: 2017-06-05 17:16:41.966787Z] Finished clobber step (success) 10:16:41 INFO - [mozharness: 2017-06-05 17:16:41.966915Z] Running read-buildbot-config step. 10:16:41 INFO - Running pre-action listener: _resource_record_pre_action 10:16:41 INFO - Running main action method: read_buildbot_config 10:16:41 INFO - Using buildbot properties: 10:16:41 INFO - { 10:16:41 INFO - "project": "", 10:16:41 INFO - "product": "firefox", 10:16:41 INFO - "who": "ryanvm@gmail.com", 10:16:41 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:16:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:16:41 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:16:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:16:41 INFO - "stage_platform": "macosx64", 10:16:41 INFO - "basedir": "/builds/slave/test", 10:16:41 INFO - "buildnumber": 176, 10:16:41 INFO - "platform": "yosemite_r7", 10:16:41 INFO - "parent_task_id": "ZPkBWxRsRumKxQB5ApcGNQ", 10:16:41 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:16:41 INFO - "slavebuilddir": "test", 10:16:41 INFO - "taskId": "Lm_lcfqBRwSTxF-yxT-g6w", 10:16:41 INFO - "branch": "mozilla-central", 10:16:41 INFO - "script_repo_revision": "production", 10:16:41 INFO - "revision": "2f77e11bf03d535873759601f73e2e65f464ba26", 10:16:41 INFO - "slavename": "t-yosemite-r7-0020", 10:16:41 INFO - "repo_path": "mozilla-central" 10:16:41 INFO - } 10:16:41 INFO - Finding installer, test and symbols from parent task. 10:16:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lm_lcfqBRwSTxF-yxT-g6w'}, attempt #1 10:16:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lm_lcfqBRwSTxF-yxT-g6w'}, attempt #1 10:16:43 INFO - Task dependencies: dBtOXC-4SZOGHVVGItj6AQ ZPkBWxRsRumKxQB5ApcGNQ 10:16:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ'}, attempt #1 10:16:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:16:43 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/test_packages.json 10:16:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/target.crashreporter-symbols.zip 10:16:43 INFO - Running post-action listener: _resource_record_post_action 10:16:43 INFO - [mozharness: 2017-06-05 17:16:43.694757Z] Finished read-buildbot-config step (success) 10:16:43 INFO - [mozharness: 2017-06-05 17:16:43.694898Z] Running download-and-extract step. 10:16:43 INFO - Running pre-action listener: _resource_record_pre_action 10:16:43 INFO - Running main action method: download_and_extract 10:16:43 INFO - mkdir: /builds/slave/test/build/tests 10:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:43 INFO - trying https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:16:43 INFO - Downloading https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:16:44 INFO - Downloaded 1328 bytes. 10:16:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:16:44 INFO - Using the following test package requirements: 10:16:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:16:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:16:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:16:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:16:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'jsshell-mac.zip'], 10:16:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:16:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:16:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:16:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:16:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 10:16:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:16:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:16:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:16:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:16:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:16:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:16:46 INFO - Content-Length response header: 57631684 10:16:46 INFO - Bytes received: 57631684 10:17:04 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:17:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:17:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:17:04 INFO - Fetch https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:17:06 INFO - Content-Length response header: 57631684 10:17:06 INFO - Bytes received: 57631684 10:17:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:17:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:17:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:17:25 INFO - Content-Length response header: 14447425 10:17:25 INFO - Bytes received: 14447425 10:17: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')]} 10:17:25 INFO - trying https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:17:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:17:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:17:27 INFO - Downloaded 67984995 bytes. 10:17:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:17:27 INFO - mkdir: /builds/slave/test/properties 10:17:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:17:27 INFO - Writing to file /builds/slave/test/properties/build_url 10:17:27 INFO - Contents: 10:17:27 INFO - build_url:https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:17:27 INFO - Running post-action listener: _resource_record_post_action 10:17:27 INFO - Running post-action listener: set_extra_try_arguments 10:17:27 INFO - [mozharness: 2017-06-05 17:17:27.898429Z] Finished download-and-extract step (success) 10:17:27 INFO - [mozharness: 2017-06-05 17:17:27.898551Z] Running populate-webroot step. 10:17:27 INFO - Running pre-action listener: _resource_record_pre_action 10:17:27 INFO - Running main action method: populate_webroot 10:17:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:17:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:17:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:17:27 INFO - '--tppagecycles', 10:17:27 INFO - '7'], 10:17:27 INFO - 'tests': ['tsvgm']}}, 10:17:27 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:17:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:17:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:17:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:17:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:17:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['damp', 'tps']}, 10:17:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'tests': ['damp', 'tps']}, 10:17:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['dromaeo_dom']}, 10:17:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:17:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:17:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:17:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:17:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['a11yr', 10:17:27 INFO - 'ts_paint', 10:17:27 INFO - 'tpaint', 10:17:27 INFO - 'sessionrestore', 10:17:27 INFO - 'sessionrestore_no_auto_restore', 10:17:27 INFO - 'tabpaint']}, 10:17:27 INFO - 'other-e10s': {'tests': ['a11yr', 10:17:27 INFO - 'ts_paint', 10:17:27 INFO - 'tpaint', 10:17:27 INFO - 'sessionrestore', 10:17:27 INFO - 'sessionrestore_no_auto_restore', 10:17:27 INFO - 'tabpaint']}, 10:17:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:17:27 INFO - 'ts_paint', 10:17:27 INFO - 'tpaint', 10:17:27 INFO - 'sessionrestore', 10:17:27 INFO - 'sessionrestore_no_auto_restore', 10:17:27 INFO - 'tabpaint']}, 10:17:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:17:27 INFO - 'ts_paint', 10:17:27 INFO - 'tpaint', 10:17:27 INFO - 'sessionrestore', 10:17:27 INFO - 'sessionrestore_no_auto_restore', 10:17:27 INFO - 'tabpaint']}, 10:17:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['a11yr', 10:17:27 INFO - 'ts_paint', 10:17:27 INFO - 'tpaint', 10:17:27 INFO - 'sessionrestore', 10:17:27 INFO - 'sessionrestore_no_auto_restore', 10:17:27 INFO - 'tabpaint']}, 10:17:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['a11yr', 10:17:27 INFO - 'ts_paint', 10:17:27 INFO - 'tpaint', 10:17:27 INFO - 'sessionrestore', 10:17:27 INFO - 'sessionrestore_no_auto_restore', 10:17:27 INFO - 'tabpaint']}, 10:17:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:17:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:17:27 INFO - 'bloom_basic_ref']}, 10:17:27 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:17:27 INFO - 'talos_options': ['--mitmproxy', 10:17:27 INFO - 'mitmproxy-recording-1.mp', 10:17:27 INFO - '--firstNonBlankPaint'], 10:17:27 INFO - 'tests': ['Quantum_1']}, 10:17:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['tsvgx', 10:17:27 INFO - 'tsvgr_opacity', 10:17:27 INFO - 'tart', 10:17:27 INFO - 'tscrollx', 10:17:27 INFO - 'cart', 10:17:27 INFO - 'tsvg_static']}, 10:17:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:17:27 INFO - 'tsvgr_opacity', 10:17:27 INFO - 'tart', 10:17:27 INFO - 'tscrollx', 10:17:27 INFO - 'cart', 10:17:27 INFO - 'tsvg_static']}, 10:17:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'talos_options': ['--disable-e10s'], 10:17:27 INFO - 'tests': ['tp5o']}, 10:17:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:17:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'talos_options': ['--disable-e10s', 10:17:27 INFO - '--xperf_path', 10:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:17:27 INFO - 'tests': ['tp5n']}, 10:17:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:17:27 INFO - 'talos_options': ['--xperf_path', 10:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:17:27 INFO - 'tests': ['tp5n']}}, 10:17:27 INFO - 'talos.zip': {'path': '', 10:17:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:17:27 INFO - Running post-action listener: _resource_record_post_action 10:17:27 INFO - [mozharness: 2017-06-05 17:17:27.920600Z] Finished populate-webroot step (success) 10:17:27 INFO - [mozharness: 2017-06-05 17:17:27.920744Z] Running create-virtualenv step. 10:17:27 INFO - Running pre-action listener: _resource_record_pre_action 10:17:27 INFO - Running main action method: create_virtualenv 10:17:27 INFO - Creating virtualenv /builds/slave/test/build/venv 10:17:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:17:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:17:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:17:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:17:28 INFO - Using real prefix '/tools/python27' 10:17:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:17:28 INFO - Installing distribute.............................................................................................................................................................................................done. 10:17:31 INFO - Installing pip.................done. 10:17:31 INFO - Return code: 0 10:17:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:17:31 INFO - Reading from file tmpfile_stdout 10:17:31 INFO - Output received: 10:17:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:17:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:17: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')]} 10:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17: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')]} 10:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17: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', 'pip>=1.5'] in /builds/slave/test/build 10:17: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 pip>=1.5 10:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:31 INFO - 'HOME': '/Users/cltbld', 10:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:31 INFO - 'LOGNAME': 'cltbld', 10:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:31 INFO - 'MOZ_NO_REMOTE': '1', 10:17:31 INFO - 'NO_EM_RESTART': '1', 10:17:31 INFO - 'PAGER': '/bin/cat', 10:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:31 INFO - 'PWD': '/builds/slave/test', 10:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:31 INFO - 'SHELL': '/bin/bash', 10:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:31 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:31 INFO - 'USER': 'cltbld', 10:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:31 INFO - 'XPC_FLAGS': '0x0', 10:17:31 INFO - 'XPC_SERVICE_NAME': '0', 10:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:17:32 INFO - Cleaning up... 10:17:32 INFO - Return code: 0 10:17:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:32 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:32 INFO - 'HOME': '/Users/cltbld', 10:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:32 INFO - 'LOGNAME': 'cltbld', 10:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:32 INFO - 'MOZ_NO_REMOTE': '1', 10:17:32 INFO - 'NO_EM_RESTART': '1', 10:17:32 INFO - 'PAGER': '/bin/cat', 10:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:32 INFO - 'PWD': '/builds/slave/test', 10:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:32 INFO - 'SHELL': '/bin/bash', 10:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:32 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:32 INFO - 'USER': 'cltbld', 10:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:32 INFO - 'XPC_FLAGS': '0x0', 10:17:32 INFO - 'XPC_SERVICE_NAME': '0', 10:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:32 INFO - Downloading/unpacking psutil>=3.1.1 10:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:17:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:17:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:17:34 INFO - Installing collected packages: psutil 10:17:34 INFO - Running setup.py install for psutil 10:17:34 INFO - building 'psutil._psutil_osx' extension 10:17:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:17:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:17:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:17:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:17:35 INFO - building 'psutil._psutil_posix' extension 10:17:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:17:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:17:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:17:35 INFO - ^ 10:17:35 INFO - 1 warning generated. 10:17:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:17:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:17:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:17:35 INFO - Successfully installed psutil 10:17:35 INFO - Cleaning up... 10:17:35 INFO - Return code: 0 10:17:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:36 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:36 INFO - 'HOME': '/Users/cltbld', 10:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:36 INFO - 'LOGNAME': 'cltbld', 10:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:36 INFO - 'MOZ_NO_REMOTE': '1', 10:17:36 INFO - 'NO_EM_RESTART': '1', 10:17:36 INFO - 'PAGER': '/bin/cat', 10:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:36 INFO - 'PWD': '/builds/slave/test', 10:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:36 INFO - 'SHELL': '/bin/bash', 10:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:36 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:36 INFO - 'USER': 'cltbld', 10:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:36 INFO - 'XPC_FLAGS': '0x0', 10:17:36 INFO - 'XPC_SERVICE_NAME': '0', 10:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:17:37 INFO - Installing collected packages: mozsystemmonitor 10:17:37 INFO - Running setup.py install for mozsystemmonitor 10:17:38 INFO - Successfully installed mozsystemmonitor 10:17:38 INFO - Cleaning up... 10:17:38 INFO - Return code: 0 10:17:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:38 INFO - 'HOME': '/Users/cltbld', 10:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:38 INFO - 'LOGNAME': 'cltbld', 10:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:38 INFO - 'MOZ_NO_REMOTE': '1', 10:17:38 INFO - 'NO_EM_RESTART': '1', 10:17:38 INFO - 'PAGER': '/bin/cat', 10:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:38 INFO - 'PWD': '/builds/slave/test', 10:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:38 INFO - 'SHELL': '/bin/bash', 10:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:38 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:38 INFO - 'USER': 'cltbld', 10:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:38 INFO - 'XPC_FLAGS': '0x0', 10:17:38 INFO - 'XPC_SERVICE_NAME': '0', 10:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:38 INFO - Downloading/unpacking jsonschema==2.5.1 10:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:17:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:40 INFO - Downloading functools32-3.2.3-2.tar.gz 10:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:17:40 INFO - warning: no files found matching '*.txt' 10:17:40 INFO - no previously-included directories found matching 'build' 10:17:40 INFO - no previously-included directories found matching 'dist' 10:17:40 INFO - no previously-included directories found matching '.git*' 10:17:40 INFO - Installing collected packages: jsonschema, functools32 10:17:40 INFO - Running setup.py install for functools32 10:17:40 INFO - warning: no files found matching '*.txt' 10:17:40 INFO - no previously-included directories found matching 'build' 10:17:40 INFO - no previously-included directories found matching 'dist' 10:17:40 INFO - no previously-included directories found matching '.git*' 10:17:40 INFO - Successfully installed jsonschema functools32 10:17:40 INFO - Cleaning up... 10:17:40 INFO - Return code: 0 10:17:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:40 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:40 INFO - 'HOME': '/Users/cltbld', 10:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:40 INFO - 'LOGNAME': 'cltbld', 10:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:40 INFO - 'MOZ_NO_REMOTE': '1', 10:17:40 INFO - 'NO_EM_RESTART': '1', 10:17:40 INFO - 'PAGER': '/bin/cat', 10:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:40 INFO - 'PWD': '/builds/slave/test', 10:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:40 INFO - 'SHELL': '/bin/bash', 10:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:40 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:40 INFO - 'USER': 'cltbld', 10:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:40 INFO - 'XPC_FLAGS': '0x0', 10:17:40 INFO - 'XPC_SERVICE_NAME': '0', 10:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:17:40 INFO - Cleaning up... 10:17:40 INFO - Return code: 0 10:17:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:40 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:40 INFO - 'HOME': '/Users/cltbld', 10:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:40 INFO - 'LOGNAME': 'cltbld', 10:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:40 INFO - 'MOZ_NO_REMOTE': '1', 10:17:40 INFO - 'NO_EM_RESTART': '1', 10:17:40 INFO - 'PAGER': '/bin/cat', 10:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:40 INFO - 'PWD': '/builds/slave/test', 10:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:40 INFO - 'SHELL': '/bin/bash', 10:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:40 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:40 INFO - 'USER': 'cltbld', 10:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:40 INFO - 'XPC_FLAGS': '0x0', 10:17:40 INFO - 'XPC_SERVICE_NAME': '0', 10:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:40 INFO - Downloading/unpacking blobuploader==1.2.4 10:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:42 INFO - Downloading blobuploader-1.2.4.tar.gz 10:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:17:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:17:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:43 INFO - Downloading docopt-0.6.1.tar.gz 10:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:17:43 INFO - Installing collected packages: blobuploader, requests, docopt 10:17:43 INFO - Running setup.py install for blobuploader 10:17:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:17:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:17:43 INFO - Running setup.py install for requests 10:17:43 INFO - Running setup.py install for docopt 10:17:44 INFO - Successfully installed blobuploader requests docopt 10:17:44 INFO - Cleaning up... 10:17:44 INFO - Return code: 0 10:17:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:44 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:17:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:44 INFO - 'HOME': '/Users/cltbld', 10:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:44 INFO - 'LOGNAME': 'cltbld', 10:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:44 INFO - 'MOZ_NO_REMOTE': '1', 10:17:44 INFO - 'NO_EM_RESTART': '1', 10:17:44 INFO - 'PAGER': '/bin/cat', 10:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:44 INFO - 'PWD': '/builds/slave/test', 10:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:44 INFO - 'SHELL': '/bin/bash', 10:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:44 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:44 INFO - 'USER': 'cltbld', 10:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:44 INFO - 'XPC_FLAGS': '0x0', 10:17:44 INFO - 'XPC_SERVICE_NAME': '0', 10:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:17:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Zkc2SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:17:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-AGCswq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:17:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-yyCtGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:17:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-FZUmDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:17:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-kv_5tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:17:44 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-sduhHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-PMg8m9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-IbyUxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-JC8mfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ZabKLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-T1Srkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-0OtHwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-Tjho53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-oOO3qD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-ajevei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:17:45 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-muQehM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:17:46 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-_YTreQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:17:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:17:46 INFO - Running setup.py install for manifestparser 10:17:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:17:46 INFO - Running setup.py install for mozcrash 10:17:46 INFO - Running setup.py install for mozdebug 10:17:46 INFO - Running setup.py install for mozdevice 10:17:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:17:46 INFO - Running setup.py install for mozfile 10:17:46 INFO - Running setup.py install for mozhttpd 10:17:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Running setup.py install for mozinfo 10:17:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Running setup.py install for mozInstall 10:17:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Running setup.py install for mozleak 10:17:47 INFO - Running setup.py install for mozlog 10:17:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Running setup.py install for moznetwork 10:17:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:17:47 INFO - Running setup.py install for mozprocess 10:17:47 INFO - Running setup.py install for mozprofile 10:17:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:17:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:17:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:17:48 INFO - Running setup.py install for mozrunner 10:17:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:17:48 INFO - Running setup.py install for mozscreenshot 10:17:48 INFO - Running setup.py install for moztest 10:17:48 INFO - Running setup.py install for mozversion 10:17:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:17:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:17:48 INFO - Cleaning up... 10:17:48 INFO - Return code: 0 10:17:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:48 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:17:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:48 INFO - 'HOME': '/Users/cltbld', 10:17:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:48 INFO - 'LOGNAME': 'cltbld', 10:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:48 INFO - 'MOZ_NO_REMOTE': '1', 10:17:48 INFO - 'NO_EM_RESTART': '1', 10:17:48 INFO - 'PAGER': '/bin/cat', 10:17:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:48 INFO - 'PWD': '/builds/slave/test', 10:17:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:48 INFO - 'SHELL': '/bin/bash', 10:17:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:48 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:48 INFO - 'USER': 'cltbld', 10:17:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:48 INFO - 'XPC_FLAGS': '0x0', 10:17:48 INFO - 'XPC_SERVICE_NAME': '0', 10:17:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-js85PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-PkwLrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-9Ofieu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-V99GpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:17:49 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)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-tpCVK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-iJAe7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-3fiBiB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-lzzi4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-DvEy4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:17:49 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-F120Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-W8RL3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-6cqnB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-FI0BIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-zCihbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-J4Yy6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-nfIPZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:17:50 INFO - Running setup.py (path:/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/pip-zehDpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:17:50 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)) 10:17:50 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)) 10:17:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:52 INFO - Downloading blessings-1.6.tar.gz 10:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:17:52 INFO - Installing collected packages: blessings 10:17:52 INFO - Running setup.py install for blessings 10:17:52 INFO - Successfully installed blessings 10:17:52 INFO - Cleaning up... 10:17:52 INFO - Return code: 0 10:17:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:17:52 INFO - Reading from file tmpfile_stdout 10:17:52 INFO - Current package versions: 10:17:52 INFO - blessings == 1.6 10:17:52 INFO - blobuploader == 1.2.4 10:17:52 INFO - docopt == 0.6.1 10:17:52 INFO - functools32 == 3.2.3-2 10:17:52 INFO - jsonschema == 2.5.1 10:17:52 INFO - manifestparser == 1.1 10:17:52 INFO - mozInstall == 1.12 10:17:52 INFO - mozcrash == 1.0 10:17:52 INFO - mozdebug == 0.1 10:17:52 INFO - mozdevice == 0.51 10:17:52 INFO - mozfile == 1.2 10:17:52 INFO - mozhttpd == 0.7 10:17:52 INFO - mozinfo == 0.9 10:17:52 INFO - mozleak == 0.1 10:17:52 INFO - mozlog == 3.4 10:17:52 INFO - moznetwork == 0.27 10:17:52 INFO - mozprocess == 0.25 10:17:52 INFO - mozprofile == 0.28 10:17:52 INFO - mozrunner == 6.13 10:17:52 INFO - mozscreenshot == 0.1 10:17:52 INFO - mozsystemmonitor == 0.3 10:17:52 INFO - moztest == 0.8 10:17:52 INFO - mozversion == 1.4 10:17:52 INFO - psutil == 3.1.1 10:17:52 INFO - requests == 1.2.3 10:17:52 INFO - wsgiref == 0.1.2 10:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:52 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 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:52 INFO - 'HOME': '/Users/cltbld', 10:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:52 INFO - 'LOGNAME': 'cltbld', 10:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:52 INFO - 'MOZ_NO_REMOTE': '1', 10:17:52 INFO - 'NO_EM_RESTART': '1', 10:17:52 INFO - 'PAGER': '/bin/cat', 10:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:52 INFO - 'PWD': '/builds/slave/test', 10:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:52 INFO - 'SHELL': '/bin/bash', 10:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:52 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:52 INFO - 'USER': 'cltbld', 10:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:52 INFO - 'XPC_FLAGS': '0x0', 10:17:52 INFO - 'XPC_SERVICE_NAME': '0', 10:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:17:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:17:54 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:17:54 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)) 10:17:54 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)) 10:17:54 INFO - Installing collected packages: marionette-driver 10:17:54 INFO - Running setup.py install for marionette-driver 10:17:54 INFO - Successfully installed marionette-driver 10:17:54 INFO - Cleaning up... 10:17:54 INFO - Return code: 0 10:17:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:17:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7fec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c802768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8fa8592e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, '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.WFpl6xj28m/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.i46JiFL1hm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:17:54 INFO - 'HOME': '/Users/cltbld', 10:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:17:54 INFO - 'LOGNAME': 'cltbld', 10:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:17:54 INFO - 'MOZ_NO_REMOTE': '1', 10:17:54 INFO - 'NO_EM_RESTART': '1', 10:17:54 INFO - 'PAGER': '/bin/cat', 10:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:17:54 INFO - 'PWD': '/builds/slave/test', 10:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:17:54 INFO - 'SHELL': '/bin/bash', 10:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:17:54 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:17:54 INFO - 'USER': 'cltbld', 10:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:17:54 INFO - 'XPC_FLAGS': '0x0', 10:17:54 INFO - 'XPC_SERVICE_NAME': '0', 10:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:17:55 INFO - Cleaning up... 10:17:55 INFO - Return code: 0 10:17:55 INFO - Running post-action listener: _resource_record_post_action 10:17:55 INFO - Running post-action listener: _start_resource_monitoring 10:17:55 INFO - Starting resource monitoring. 10:17:55 INFO - [mozharness: 2017-06-05 17:17:55.118412Z] Finished create-virtualenv step (success) 10:17:55 INFO - [mozharness: 2017-06-05 17:17:55.119395Z] Running install step. 10:17:55 INFO - Running pre-action listener: _resource_record_pre_action 10:17:55 INFO - Running main action method: install 10:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:17:55 INFO - Reading from file tmpfile_stdout 10:17:55 INFO - Detecting whether we're running mozinstall >=1.0... 10:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:17:55 INFO - Reading from file tmpfile_stdout 10:17:55 INFO - Output received: 10:17:55 INFO - Usage: mozinstall [options] installer 10:17:55 INFO - Options: 10:17:55 INFO - -h, --help show this help message and exit 10:17:55 INFO - -d DEST, --destination=DEST 10:17:55 INFO - Directory to install application into. [default: 10:17:55 INFO - "/builds/slave/test"] 10:17:55 INFO - --app=APP Application being installed. [default: firefox] 10:17:55 INFO - mkdir: /builds/slave/test/build/application 10:17:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:18:17 INFO - Reading from file tmpfile_stdout 10:18:17 INFO - Output received: 10:18:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:18:17 INFO - Running post-action listener: _resource_record_post_action 10:18:17 INFO - [mozharness: 2017-06-05 17:18:17.016368Z] Finished install step (success) 10:18:17 INFO - [mozharness: 2017-06-05 17:18:17.016537Z] Running setup-mitmproxy step. 10:18:17 INFO - Running pre-action listener: _resource_record_pre_action 10:18:17 INFO - Running main action method: setup_mitmproxy 10:18:17 INFO - Skipping: mitmproxy is not required 10:18:17 INFO - Running post-action listener: _resource_record_post_action 10:18:17 INFO - [mozharness: 2017-06-05 17:18:17.017247Z] Finished setup-mitmproxy step (success) 10:18:17 INFO - [mozharness: 2017-06-05 17:18:17.017360Z] Running run-tests step. 10:18:17 INFO - Running pre-action listener: _resource_record_pre_action 10:18:17 INFO - Running main action method: run_tests 10:18:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:18:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:18:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:18:17 INFO - 2017-06-05 10:18:17.032 screenresolution[1626:7471] starting screenresolution argv=screenresolution get 10:18:17 INFO - 2017-06-05 10:18:17.034 screenresolution[1626:7471] Display 0: 1600x1200x32@60 10:18:17 INFO - 2017-06-05 10:18:17.040 screenresolution[1627:7473] starting screenresolution argv=screenresolution list 10:18:17 INFO - Available Modes on Display 0 10:18:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:18:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:18:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:18:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:18:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:18:17 INFO - Intel Iris: 10:18:17 INFO - Chipset Model: Intel Iris 10:18:17 INFO - Type: GPU 10:18:17 INFO - Bus: Built-In 10:18:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:18:17 INFO - Vendor: Intel (0x8086) 10:18:17 INFO - Device ID: 0x0a2e 10:18:17 INFO - Revision ID: 0x0009 10:18:17 INFO - Displays: 10:18:17 INFO - Display: 10:18:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:18:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:18:17 INFO - Display Serial Number: 1600x1200 60 10:18:17 INFO - Main Display: Yes 10:18:17 INFO - Mirror: Off 10:18:17 INFO - Online: Yes 10:18:17 INFO - Rotation: Supported 10:18:17 INFO - Return code: 0 10:18:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:18:17 INFO - Python 2.7.3 10:18:17 INFO - Return code: 0 10:18:17 INFO - grabbing minidump binary from tooltool 10:18: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')]} 10:18:17 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 0x10c787c60>, '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 0x10c51ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c545be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:18:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:18:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:18:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:18:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:18:17 INFO - Return code: 0 10:18:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:18:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:18:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:18:17 INFO - ENV: RUST_BACKTRACE is now 1 10:18:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:18:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:18:17 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/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:18:17 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/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFpl6xj28m/Render', 10:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:18:17 INFO - 'HOME': '/Users/cltbld', 10:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:18:17 INFO - 'LOGNAME': 'cltbld', 10:18:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:18:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:18:17 INFO - 'MOZ_NO_REMOTE': '1', 10:18:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:18:17 INFO - 'NO_EM_RESTART': '1', 10:18:17 INFO - 'PAGER': '/bin/cat', 10:18:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:18:17 INFO - 'PWD': '/builds/slave/test', 10:18:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:18:17 INFO - 'RUST_BACKTRACE': '1', 10:18:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:18:17 INFO - 'SHELL': '/bin/bash', 10:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i46JiFL1hm/Listeners', 10:18:17 INFO - 'TMPDIR': '/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/', 10:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:18:17 INFO - 'USER': 'cltbld', 10:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:18:17 INFO - 'XPC_FLAGS': '0x0', 10:18:17 INFO - 'XPC_SERVICE_NAME': '0', 10:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:18:17 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/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:18:17 INFO - mozversion application_buildid: 20170605090223 10:18:17 INFO - mozversion application_changeset: 2f77e11bf03d535873759601f73e2e65f464ba26 10:18:17 INFO - mozversion application_display_name: Nightly 10:18:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:18:17 INFO - mozversion application_name: Firefox 10:18:17 INFO - mozversion application_remotingname: firefox 10:18:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:18:17 INFO - mozversion application_vendor: Mozilla 10:18:17 INFO - mozversion application_version: 55.0a1 10:18:17 INFO - mozversion platform_buildid: 20170605090223 10:18:17 INFO - mozversion platform_changeset: 2f77e11bf03d535873759601f73e2e65f464ba26 10:18:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:18:17 INFO - mozversion platform_version: 55.0a1 10:18:17 INFO - using testdate: 1496683097 10:18:17 INFO - actual date: 1496683097 10:18:17 INFO - starting webserver on 'localhost:49228' 10:18:17 INFO - SUITE-START | Running 2 tests 10:18:17 INFO - TEST-START | dromaeo_css 10:18:17 INFO - operating with platform_type : mac_ 10:18:17 INFO - Initialising browser for dromaeo_css test... 10:18:17 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpEAGtaw/profile 10:18:17 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:18:51 INFO - TEST-INFO | 1637: killed by SIGTERM 10:18:51 INFO - Browser initialized. 10:18:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:18:51 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpEAGtaw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:53 INFO - PID 1647 | 2017-06-05 10:18:53.532 plugin-container[1648:8176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:18:53 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:53 INFO - PID 1647 | 2017-06-05 10:18:53.532 plugin-container[1648:8176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:18:53 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:53 INFO - PID 1647 | 2017-06-05 10:18:53.533 plugin-container[1648:8176] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:18:54 INFO - PID 1647 | 2017-06-05 10:18:54.254 plugin-container[1649:8221] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a4f, name = 'com.apple.tsm.portname' 10:18:54 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:54 INFO - PID 1647 | 2017-06-05 10:18:54.255 plugin-container[1649:8221] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.CFPasteboardClient' 10:18:54 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:18:54 INFO - PID 1647 | 2017-06-05 10:18:54.255 plugin-container[1649:8221] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:03 INFO - PID 1647 | RSS: Main: 259846144 10:19:03 INFO - PID 1647 | 10:22:06 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:22:07 INFO - PID 1647 | RSS: Main: 267890688 10:22:07 INFO - PID 1647 | 10:25:09 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 10:25:10 INFO - PID 1647 | RSS: Main: 282259456 10:25:10 INFO - PID 1647 | 10:28:12 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 10:28:12 INFO - PID 1647 | RSS: Main: 285466624 10:28:12 INFO - PID 1647 | 10:31:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 10:31:15 INFO - PID 1647 | RSS: Main: 289169408 10:31:15 INFO - PID 1647 | 10:34:18 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 10:34:19 INFO - PID 1647 | RSS: Main: 289087488 10:34:19 INFO - PID 1647 | 10:37:20 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 10:37:21 INFO - PID 1647 | RSS: Main: 289148928 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | __start_tp_report 10:37:21 INFO - PID 1647 | _x_x_mozilla_page_load 10:37:21 INFO - PID 1647 | _x_x_mozilla_page_load_details 10:37:21 INFO - PID 1647 | |i|pagename|runs| 10:37:21 INFO - PID 1647 | |0;dojo.html;3574.928501429971;3583.1758695500016;3597.9280414391696;3637.4725664778607;3640.726945479088;4813.711177329358;6114.0540674973245;6264.874702505947;6279.49764018878;6289.905651415221;5413.323334583177;6134.049222370529;6145.508359331254;6147.323794382614;6202.937970620293;5316.9734151329185;5378.273933019051;5388.43421440749;5393;5404.108322126855;5418.512333889949;5497.752601132958;5513.724313784315;5555.2778138842095;5557.499825015748;5327.413984461706;5455.8363249102595;5479.6164268501225;5528.226048353214;5546.084895992157;4413.713108647928;5416.512513873731;5425.294711687495;5456.181572764117;5491.780328786844;5318.441563635801;5370.7046112463795;5412.83761487156;5427.240186373911;5443.836684899459;6157.076438534211;6206.844828879269;6229.25248970122;6248.187735594389;6263.185785847856;6237.407446292595;6443.226812782496;6522.804315870531;6565.406664367596;6602.7689030883685;5590.776368945278;6699.59802411852;6721.227058888241;6729.05793188954;6745.932540674628;6341.524385671093;6361.777337793155;6361.872762544724;6375.8406039848915;6376.553641245161;6603.966980165068;6695.698693558801;6813.863722725518;6816.216135144423;6829.282925292872;6364.681765911686;6376.139221205078;6417.807465776034;6490.513211509061;6545.607263564201;6735.696893639798;6741.561798483083;6742.898856517156;6761.0872532208505;6769.187697476236;5496.477834605706;6570.441512471493;6614.6361950093205;6621.635810030494;6624.138861948012;5659.490645841894;6449.193850768654;6456.903146452807;6480.4815614750705;6650.20197576294;5337.146056630998;5349.866253343697;5350.250964864922;5351.652142610717;5382.2464854920345;4757.26262429324;4768.809247630125;4769.093872164278;4801.975990120027;4814.831480898151;5072.9746351268;6204.255489341218;6270.090836828635;6282.905756413657;6376.872462550723;5562.888742225133;6728.7308507659145;6740.39336459721;6752.5948443093575;6765.391114799691;6193.998256755074;6335.3981371769605;6344.587601805868;6347.428731414195;6400.967995159995;5775;5818.709064546756;5837.795677151279;5934.673592952429;5956.1065840123465;6891.517593768488;6927.7228910845015;6932.896006559805;6950.687219075053;6957.060796792571;6766.627835469097;6767.560108592926;6776.186857577122;6778.152730908637;6795.048693182859;6626.602403855784;6803.251642319242;6941.444684425335;6949;6962.408195303456;6304.65324407162;6329.30377658448;6357.396047375492;6365.458935990493;6413.551051426355;6762.289959554176;6774.288699686462;6781.152355955505;6783.084283621648;6819.659017049227;6370.426661600478;6416.230052393743;6434.131392261985;6502.219733631994;6509.674516274073;6453.870922581428;6469.676516174267;6503;6516.446102081376;6521.315261897433;6339.90490142639;6384.457321127757;6406.87186256263;6407.519436042222;6421.967890160519;6360.045993101072;6385.68071596409;6486;6500.479961603155;6532;6305.716242768995;6336.334684858023;6369.7452101915405;6381.170447841937;6395.872082558416 10:37:21 INFO - PID 1647 | |1;ext.html;773.8220209351849;816.7549735079476;826.9751907442775;1257.0886107572005;1319.122783349073;38374.23251534968;39328.62349817761;39474.802625986864;39478.80260598693;39597.20805583886;39628.20743585127;39679.61121360753;39688.60311396885;39759.40360894589;39850.800745996305;6659.4672426206025;7682.308592226685;8117.107118216992;8296.128906464824;8325.292350150245;8219.095899451055;8257.669693212265;8364.121767214447;8378.204070613281;8396.202360775716;8312.04411492677;8370.58147092646;8387.83224335513;8401.285890699299;8424.578771061453;8291.253787159125;8354.791130221733;8371.246587807096;8387.538685372301;8408.327333813342;6529.477641788645;8124.878126828103;8129.146439623813;8142.470739401979;8145.755627331189;36734.265314693825;36992.44511332332;37245;37315.62684373149;37390.878273651564;3434.5019972104155;3464.4630082337276;3470.8785192518394;3475.322312149132;3476.756627036083;30396.392072158433;30419.23951901198;30454;30497;30555.08334749961;3096.674849140853;3097.4579448596633;3107.8446077696026;3111.2688518198206;3121.547375630521;254176;255740;289698.9999999979;316121;316360.2545961812;47123.764381177876;47892.5210747895;48001.519984800405;48044.79888002828;48162;50462;50463;50469.74765126151;50661.240081398806;54627.72686136544;14229.28853557318;14286.21425821569;14335.426582936774;14388.424463021362;14432.206228657315;11433.970942615204;11505;11526.59656912004;11560.248583842023;11597.014253788522;13865.23741194244;13974.301284935713;14145.939054570948;14158.29208539569;14265.286735663176;5558.722063896789;5915.64506129625;6172.783952561639;6196;6201.224846894138;963.6001059560344;964.4743614729989;966.8259713251541;1010.3786171504619;1013.8124446977333;19434.80565194358;21954.824947876816;22389.32832015042;22962.65556016661;23623.527529449315;836.4228682209256;980.3676628574672;1029.130384824822;1085.4898197846999;1119.3004372267333;9334;11230.3261804292;11554.248973816675;11624.418779061014;12124.030077593772;283908;285068;285997;287195.6402179883;287467;55715.44284557184;55896.72051639716;55915;55985.44014559884;56176.71911640416;1426.3866537389022;1456.1772598482037;1485.814273215848;1495.7307684616655;1503.2784263553556;22009.889950550787;22272.554548908607;22851.88574057119;23036.4240893979;23673.64489532693;50444.24333634925;51827.74086129545;51903.74048129886;51907.44277671676;52049.739751301;289761;292213.9282339492;292356;292568.53715731285;292993;34557.65442345645;36105.0973725659;36243.45634815533;36541.634583653824;36761;43577;43637.78181109074;46457.30314045224;46752.532474674816;46790;28355.858220708764;29744.55383169211;30774.38451230918;31262.37475250437;31724.524132138496;30018.549721753756;31307.84346078255;31553.684463155994;31722.841385793847;31786.205344866565;31324.373512530077;38252.426213607374;38295.2340953185;38296;38296.42555361616;30082.849585752807;30319.090427286334;30462.23844403819;31661.67552406964;32373.51439728453;22280;24103.63844542298;24467;24524;24590.139345122836 10:37:21 INFO - PID 1647 | |2;jquery.html;359.7769382982551;389.7875657766517;411.7755823076425;424.2215534494203;449.2721790699068;21864;22636.54726905463;22698.319050428472;23321;24451.75548244517;24289.878550607224;24812.379690507747;24829.255122346312;24904;24922.626160607604;5497.202905578689;5555.000099982001;5838.41615838416;6255.12428260044;6269.592476489036;5390.299261096052;6083.365376452546;6221.937780622181;6237.157983672214;6258.906116408235;6111.602745821529;6193.969030154843;6203.596766210205;6205.193324867761;6209.720562574695;5422.53908417785;6073.939260607403;6186.84532886677;6194;6214.285357183891;5453.345598528202;5496.917546236769;5509.862253443696;5510.889782204334;5515.944840551583;28226.717732822613;28843.711562884313;29160.416791664262;29421.970231042025;29581.55627665587;2177.292379976514;2248.718910136233;2252.414372263207;2259.1754009786473;2266;18759.437216883514;18781.436556903314;18960.81039189618;19061.856288622726;19076.71384929227;2053.5276886316083;2105.4420578546697;2160.82713382929;2186.7922547357975;2187.453136715821;109791;157368;169207;169966;187933;10842.512086956105;12848.614541563767;12991;13197.142185757895;13343.73312533744;13736.93131534356;16202.918985405233;16689.582760431087;16705.081220532986;16762.413315533897;9050.818983620291;9083.227925626263;9115.225205857443;9264.44413335202;9378.67174648884;8982.505962172012;9009.279257659371;9044.095590440904;9046.638134474679;9061.818763624691;7029.507934444642;8318.584070796436;8396.412251142483;8443.240108390275;8499;2250.27991042868;2252.549490101986;2259.469394278419;2262.8755418452142;2444.902203911859;912.748994026648;912.9680461183959;933.6872147830417;950.4772375193616;971.3540495570367;18482.260709571736;20521.89739051325;21805.67291490629;22005.66991495129;22082.558348833256;960.2989817433313;963.8987906269881;1001.3040936549029;1006.4773293472845;1015.4212099103588;10990.505427255634;11341.773164536828;11375.317480951004;11454.885451145548;11570.479328430072;461530;480590.5970470195;515415;536162;552125;138680;139363;140116;142623;148393;911.3893691226893;950.9809803804023;1132.224426268009;1167.5971789732528;1167.8481797366287;21487.78512214858;23382.64926026077;23509.294721158392;23554.173499180208;23589;61093.69453152706;63777;67681.3231867675;68157;70032;78324;79312.60343698245;88028;88437.23125537582;89110.10889891277;10110.646127385506;10203.84694229602;10252.846207307046;10267.178625710074;10296.639617613348;16391.262393192574;17261.482155535356;18360.72458913144;18399.264029438673;18466;14897.851021489647;14987.100773953598;15036.398544058115;15112.773308400163;15214.847851521343;11664.950154486138;12912.418941147484;13963.301834908418;14000.789988150389;14155.079919805179;15598.220088995278;15633.76549351738;15781.68436631238;15849.762253565976;15908.761368579711;10353.171746260432;10381.325213861075;10421.635242766466;10451.163906887583;10474.633387831389;15052;15168.544943651708;15195;15313.923430382776;15318.157501337533 10:37:21 INFO - PID 1647 | |3;mootools.html;1209.8366720492736;1380.6962468256984;1432.6561625209952;1450.2748625687157;1461.2255504582572;11592.826107608393;11676.766464670702;12223.205491643037;12230.38848057597;12346;11878.584249551268;12016.699582510442;12076.09429292802;12091.637250882466;12105.636830895088;2506.636537702032;2548.5412625727363;2571.742825717432;2576.3687896465394;2577.2397142842906;2091.665333546633;2186.125549780085;2470.1848390031246;2503.4116982509095;2545.7454254574486;2515.4466017476125;2519.7732204101626;2543.504016716741;2544.6183072539084;2545.681789776278;2395.772401621843;2416.7704068113503;2416.903323867043;2418.1415597462824;2429.671994280767;2483.615039668854;2501.062101711858;2501.137107697841;2527.772500474966;2545.121932933135;11944.402779861059;12071.215371000857;12094.697632559168;12104.273743575413;12110.939445302716;6462.386073323027;6673.165854268216;6702.430293425016;6722.0253063305545;6725.831854203635;3788.8863334099747;3985.70107241958;4213.5154457237195;4252.787360631987;4289.420928174688;1267.4803330634395;1272.872712728729;1279.1429742072833;1303.283194243172;1315.3291821171176;13742.175469471864;16484.34062637493;16554.668906622042;16593.336266549322;16870.325186992504;5476.972615136979;5545.251391062236;5631.465010823965;5742.28221472316;5744.885102298014;12085.154039217347;12234.449449774782;12341;12341.444634991267;12402.75194496105;6448.097266382711;6453.354664533509;6540.901886471612;6541.574797638234;6558.114654521674;6133.754649813957;6192.349803270682;6193.349698281616;6202.379762023852;6205.22434695663;4336.02439451121;4411.580899814512;4425.159219748302;4445.73325600465;4521.751303678264;1172.3317708905843;1204.855417349906;1273.726148877977;1285.4408332375447;1320.3134370127673;816.391788117856;818.2962652119173;839.3369238301796;850.9319254459629;876.8334016536837;6639;6892.827679308058;7302.8174295641975;7315.305046020726;7412.407007439392;925.5418567808978;961.9230461563059;966.5070813884969;974.1573538888952;1000.7898341348252;3669.064388580948;3741.569719482294;4378.934315985262;4639.976800115978;4698.694584852042;13616.863831361758;15382.615434614225;15576.532704018897;15602.453914112952;15652.608684782745;12604.180728252633;15391.923040384727;15506.302216400063;15508.991915525456;15524;8276.586170691442;8596.828063438696;8615.956920215483;8616;8772.517511536927;7126.643667816485;7163.247858974941;7227.5611258296585;7574.886376704244;7649.770506884803;13426.865731342952;13762.724745505235;14020.018598697994;14043.789343159657;14104.576862694135;11634.592789252336;14053.648658783202;14150.221737804519;14245.857541424453;14259.144451332953;9667.516624168904;9797;9818.067283608047;9990.900090999288;9997.450140242356;10897.400642964712;10993.120550355792;11019.228653994143;11029.44852757343;11044;9036.45781253127;9102;9312.72061838146;9518.286128540249;9523.809523809623;9151.496667683075;9202.171804537624;9279.164875161268;9425.764355891157;9492.85760713576;7806.1803510632835;8380.706675266336;8404.991401031915;8468.237858592756;8546.743597692079;5931.169636250842;6137.539684523589;6181.721822517908;6484.448821850006;6573.835654108686;6217.862204743573;6227.221597300338;6544.509161812788;6685.130932978682;6685.197776266879 10:37:21 INFO - PID 1647 | |4;prototype.html;350.1613635343353;371.71006614840434;390.7147782119053;398.0367510624289;398.64321432318076;4878.121938051149;5492.286002819638;5968.8360769649935;6309.526785491095;6359.300476947532;5175.974120129404;5610.905873354697;6081.331053584102;6083.965725826609;6276.968615156918;4792.736399498026;4800.967791924735;4811.518848115178;4818.301346304793;4840.273958906157;3626.673599376056;4781.952180478185;4788.305695674125;4832.154372984731;4868.951310486903;4333.848315308965;4793.880152996186;4801.567858892699;4809.663323567352;4812.975935120319;4802.687825291362;4803.207470767319;4810.542998415145;4817.494163112875;4833.32333473314;4267.445232119804;4294.806733696991;4299.097189590189;4301.784910754481;4302.913941721148;5836.212111364954;6137.925862974006;6156.168917196166;6203.47270482005;6212.1303017577575;5057.216131499624;5738.684372359518;5746.02317606008;5747.827565173052;5788.97105514474;5552;5634.690092044936;6107.465012436694;6447.903281450782;6603.405693487609;4316.661834831181;4919.581835543947;4928.285398617216;4937.1606826839525;4941.555260026615;5646.858828529279;6789.18529776425;7074.893876591804;7119.359257666835;7229;4313.374560688682;4401.779911004437;4925.064237794808;4974.1543937530705;5033.295338652591;4621.930671039937;5119.385673719177;5135.101357262464;5147.7683504242395;5153.948460515347;5475;5644.181593668895;5698.601097923106;5739.311282646109;5747.971260143672;5342.1185504392115;6457.902156633384;6644.833879152963;6669.499787515956;6717.764878229238;1501.617087642666;1549.9612509687122;1690.8562772164503;1753.587906841891;1760.2079064421062;1020.3061917895902;1058.4072919165292;1186.3890096600257;1222.6821026533166;1249.8000319948947;5080.695158290514;5585.8603534911135;6216.818804427235;6249;6271.133109245369;6994.825129371807;7008.684609192598;7016.614086225204;7026.683799229047;7041.5422997505;5104.897902041938;5382.528269266301;5428.8099916503525;6304.432601065926;6343.238811342669;3290.8646516951612;3648.5986541480415;3655.670989610948;3659;3668.220912857262;6763.391294783395;7181.820454488575;7193.5324203926575;7206.495545311778;7210.387117095001;6388.648624325707;6476.708548115346;6640.402363787186;7091.787246382617;7216.458765592602;6501.0573466847045;6549.2468366137555;6568.0772056434425;7185.784426467214;7216.96391518039;6183.5671502994355;6948.826279343057;6951.339622735832;6984.895226571504;6986.9650651746415;6801.455883529405;6809.182898052265;6843.794686159423;6852.314768523307;6868.656567171522;6567.2447668516825;6734.191896972395;6766.966165169143;7161.641917904188;7162.964185179041;4200.264953633041;4238.131183107394;4576.359309696579;4934.679245849008;5022.397312322545;6076.939230607637;6963.199232088375;6991.545549539467;6998.440124790106;7019.227884932756;5406.297181366397;6615.4530505754665;6689.632070236184;6707.228668702966;6750.729970801113;5790.741610771886;6451.7419103274415;6709.329067093252;6724.007816521601;6741.325867413416;6194.38056194377;6245.344238854855;6558.688262347454;6586.99096775488;6614.801555953329;6097.4512293891985;6678.23200331968;6698.799036028732;6712.966435167793;6720.8319792005595;5498.615096943176;5669.404712505293;6509.381608747161;6516.315786842313;6526.608403495806 10:37:21 INFO - PID 1647 | |5;yui.html;649.5972497051827;770.4991755359019;791.5962858941941;801.9157988411221;873.0570983337993;16963.66072678547;17415.3033878645;17489.950602963814;17506.124693765323;17512.562185945357;15180.466561121922;15928.964617299867;16259.51221463358;16389.262483188257;16415.261313240902;907.3693782820942;919.9448033118001;926.3006430145265;926.9942112807612;927.6660402255183;801.7594721583531;931.1340453378356;947.9336446448751;956.2493442647523;957.6265256549916;977.4233202410576;1002.91475224606;1008.8940661230539;1010.0757806606946;1014.7919676466344;997.4065431068502;1004.0712341084504;1004.2518323848707;1004.688546550568;1008.6570566007556;729.6351824087956;746.8095635612884;748.4910261022502;748.8056549803056;751.9248075192492;17427.47717568475;17664.293428262856;17681.55796105095;17844.28622855084;17875.642487150184;14920.403183872631;17918.372856950074;18257.269709211614;18445.63108737831;18578.44264672062;14646.707065858729;14746.829431672242;14821.925890370587;14832.184229867353;14839.851601483955;3511.964880351215;3536.664016918389;3541.415666415064;3575.0704816747575;3577.874774382884;134715;136477.999999999;137687;139329.30335348358;140274;13157.276349800853;13620.387082581308;13738.381772820247;13827.170369777645;13924;25903;26407.33981650474;26709.19872403831;26721.86639066831;26798.5980210297;7719.65261563231;7820.217978202248;7836.962389778699;7937.841243175104;7960.4825686330205;3946.1910308386714;3991.940120898189;4010.5187377514885;4013.9397909031386;4019.8191081401405;3644.526211592476;4457.353683715908;4493.865184044484;4500.167469018176;4521.4574251089425;917.7155081924491;952.3381250031161;964.3153361113688;964.3876138652007;966.1256562810527;638.7030031035498;651.5960104735;666.6766618190173;672.4855485553555;673.9494537909676;11372.090232781358;11722;11744.765104697859;12029.398530073462;12384.75230495385;608.1243010065491;634.1375729008545;657.8782925158861;658.0228360884083;661.048090749329;5381.19282107687;5511.724413779295;5580.414056524088;5676.545876329967;5815.156802263648;147182;150226;150269.24865375823;152065;153651;110485;123216.15175772371;125206.7479325195;132776;134228.32885835704;97547;101307.49346253369;102547;103760;104677;10326.948365258126;10842.674719758419;11882.821757673815;11990.640280791591;12364;33729.83135084309;37747.81126094352;40942.79528602338;41759.956001099;44177.895552611444;67841;86436.56781716051;87068.5646571763;87448;90002.54998725226;16089.597760055623;16706.4152754653;17430.041347725997;17478.12609369552;17544.736828947833;18623.906880466053;18770.343037993604;18979.71530427072;18987.715184271972;19050.618987620448;16590.419335323204;17877.37429189972;17998.820011799715;18062.36781712634;18169.36407225741;16431.507054788377;16578.336866525206;17233.655326893644;17511.737323939895;17904;16788.328466861676;17388.565285867953;17480.82519174792;17497.56256093557;17781;11461.656150315504;11535.250208736405;12038.518459261883;12314.50741970347;12520.937395312965;12640.30478323701;12832.294223817406;13592.11651242666;13631.386587603383;13636.795448068486 10:37:21 INFO - PID 1647 | __end_tp_report 10:37:21 INFO - PID 1647 | __start_cc_report 10:37:21 INFO - PID 1647 | _x_x_mozilla_cycle_collect,461 10:37:21 INFO - PID 1647 | __end_cc_report 10:37:21 INFO - PID 1647 | __startTimestamp1496684241017__endTimestamp 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | ------- Summary: start ------- 10:37:21 INFO - PID 1647 | Number of tests: 6 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6106.05 Median:6361.87 stddev:725.65 (11.4%) stddev-sans-first:700.18 10:37:21 INFO - PID 1647 | Values: 3574.9 3583.2 3597.9 3637.5 3640.7 4813.7 6114.1 6264.9 6279.5 6289.9 5413.3 6134.0 6145.5 6147.3 6202.9 5317.0 5378.3 5388.4 5393.0 5404.1 5418.5 5497.8 5513.7 5555.3 5557.5 5327.4 5455.8 5479.6 5528.2 5546.1 4413.7 5416.5 5425.3 5456.2 5491.8 5318.4 5370.7 5412.8 5427.2 5443.8 6157.1 6206.8 6229.3 6248.2 6263.2 6237.4 6443.2 6522.8 6565.4 6602.8 5590.8 6699.6 6721.2 6729.1 6745.9 6341.5 6361.8 6361.9 6375.8 6376.6 6604.0 6695.7 6813.9 6816.2 6829.3 6364.7 6376.1 6417.8 6490.5 6545.6 6735.7 6741.6 6742.9 6761.1 6769.2 5496.5 6570.4 6614.6 6621.6 6624.1 5659.5 6449.2 6456.9 6480.5 6650.2 5337.1 5349.9 5350.3 5351.7 5382.2 4757.3 4768.8 4769.1 4802.0 4814.8 5073.0 6204.3 6270.1 6282.9 6376.9 5562.9 6728.7 6740.4 6752.6 6765.4 6194.0 6335.4 6344.6 6347.4 6401.0 5775.0 5818.7 5837.8 5934.7 5956.1 6891.5 6927.7 6932.9 6950.7 6957.1 6766.6 6767.6 6776.2 6778.2 6795.0 6626.6 6803.3 6941.4 6949.0 6962.4 6304.7 6329.3 6357.4 6365.5 6413.6 6762.3 6774.3 6781.2 6783.1 6819.7 6370.4 6416.2 6434.1 6502.2 6509.7 6453.9 6469.7 6503.0 6516.4 6521.3 6339.9 6384.5 6406.9 6407.5 6422.0 6360.0 6385.7 6486.0 6500.5 6532.0 6305.7 6336.3 6369.7 6381.2 6395.9 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:44620.59 Median:3473.10 stddev:75581.95 (2176.2%) stddev-sans-first:75722.27 10:37:21 INFO - PID 1647 | Values: 773.8 816.8 827.0 1257.1 1319.1 38374.2 39328.6 39474.8 39478.8 39597.2 39628.2 39679.6 39688.6 39759.4 39850.8 6659.5 7682.3 8117.1 8296.1 8325.3 8219.1 8257.7 8364.1 8378.2 8396.2 8312.0 8370.6 8387.8 8401.3 8424.6 8291.3 8354.8 8371.2 8387.5 8408.3 6529.5 8124.9 8129.1 8142.5 8145.8 36734.3 36992.4 37245.0 37315.6 37390.9 3434.5 3464.5 3470.9 3475.3 3476.8 30396.4 30419.2 30454.0 30497.0 30555.1 3096.7 3097.5 3107.8 3111.3 3121.5 254176.0 255740.0 289699.0 316121.0 316360.3 47123.8 47892.5 48001.5 48044.8 48162.0 50462.0 50463.0 50469.7 50661.2 54627.7 14229.3 14286.2 14335.4 14388.4 14432.2 11434.0 11505.0 11526.6 11560.2 11597.0 13865.2 13974.3 14145.9 14158.3 14265.3 5558.7 5915.6 6172.8 6196.0 6201.2 963.6 964.5 966.8 1010.4 1013.8 19434.8 21954.8 22389.3 22962.7 23623.5 836.4 980.4 1029.1 1085.5 1119.3 9334.0 11230.3 11554.2 11624.4 12124.0 283908.0 285068.0 285997.0 287195.6 287467.0 55715.4 55896.7 55915.0 55985.4 56176.7 1426.4 1456.2 1485.8 1495.7 1503.3 22009.9 22272.6 22851.9 23036.4 23673.6 50444.2 51827.7 51903.7 51907.4 52049.7 289761.0 292213.9 292356.0 292568.5 292993.0 34557.7 36105.1 36243.5 36541.6 36761.0 43577.0 43637.8 46457.3 46752.5 46790.0 28355.9 29744.6 30774.4 31262.4 31724.5 30018.5 31307.8 31553.7 31722.8 31786.2 31324.4 38252.4 38295.2 38296.0 38296.4 30082.8 30319.1 30462.2 31661.7 32373.5 22280.0 24103.6 24467.0 24524.0 24590.1 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:36229.70 Median:12449.71 stddev:87879.88 (705.9%) stddev-sans-first:88085.14 10:37:21 INFO - PID 1647 | Values: 359.8 389.8 411.8 424.2 449.3 21864.0 22636.5 22698.3 23321.0 24451.8 24289.9 24812.4 24829.3 24904.0 24922.6 5497.2 5555.0 5838.4 6255.1 6269.6 5390.3 6083.4 6221.9 6237.2 6258.9 6111.6 6194.0 6203.6 6205.2 6209.7 5422.5 6073.9 6186.8 6194.0 6214.3 5453.3 5496.9 5509.9 5510.9 5515.9 28226.7 28843.7 29160.4 29422.0 29581.6 2177.3 2248.7 2252.4 2259.2 2266.0 18759.4 18781.4 18960.8 19061.9 19076.7 2053.5 2105.4 2160.8 2186.8 2187.5 109791.0 157368.0 169207.0 169966.0 187933.0 10842.5 12848.6 12991.0 13197.1 13343.7 13736.9 16202.9 16689.6 16705.1 16762.4 9050.8 9083.2 9115.2 9264.4 9378.7 8982.5 9009.3 9044.1 9046.6 9061.8 7029.5 8318.6 8396.4 8443.2 8499.0 2250.3 2252.5 2259.5 2262.9 2444.9 912.7 913.0 933.7 950.5 971.4 18482.3 20521.9 21805.7 22005.7 22082.6 960.3 963.9 1001.3 1006.5 1015.4 10990.5 11341.8 11375.3 11454.9 11570.5 461530.0 480590.6 515415.0 536162.0 552125.0 138680.0 139363.0 140116.0 142623.0 148393.0 911.4 951.0 1132.2 1167.6 1167.8 21487.8 23382.6 23509.3 23554.2 23589.0 61093.7 63777.0 67681.3 68157.0 70032.0 78324.0 79312.6 88028.0 88437.2 89110.1 10110.6 10203.8 10252.8 10267.2 10296.6 16391.3 17261.5 18360.7 18399.3 18466.0 14897.9 14987.1 15036.4 15112.8 15214.8 11665.0 12912.4 13963.3 14000.8 14155.1 15598.2 15633.8 15781.7 15849.8 15908.8 10353.2 10381.3 10421.6 10451.2 10474.6 15052.0 15168.5 15195.0 15313.9 15318.2 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7262.64 Median:3123.15 stddev:4574.01 (146.5%) stddev-sans-first:4564.22 10:37:21 INFO - PID 1647 | Values: 1209.8 1380.7 1432.7 1450.3 1461.2 11592.8 11676.8 12223.2 12230.4 12346.0 11878.6 12016.7 12076.1 12091.6 12105.6 2506.6 2548.5 2571.7 2576.4 2577.2 2091.7 2186.1 2470.2 2503.4 2545.7 2515.4 2519.8 2543.5 2544.6 2545.7 2395.8 2416.8 2416.9 2418.1 2429.7 2483.6 2501.1 2501.1 2527.8 2545.1 11944.4 12071.2 12094.7 12104.3 12110.9 6462.4 6673.2 6702.4 6722.0 6725.8 3788.9 3985.7 4213.5 4252.8 4289.4 1267.5 1272.9 1279.1 1303.3 1315.3 13742.2 16484.3 16554.7 16593.3 16870.3 5477.0 5545.3 5631.5 5742.3 5744.9 12085.2 12234.4 12341.0 12341.4 12402.8 6448.1 6453.4 6540.9 6541.6 6558.1 6133.8 6192.3 6193.3 6202.4 6205.2 4336.0 4411.6 4425.2 4445.7 4521.8 1172.3 1204.9 1273.7 1285.4 1320.3 816.4 818.3 839.3 850.9 876.8 6639.0 6892.8 7302.8 7315.3 7412.4 925.5 961.9 966.5 974.2 1000.8 3669.1 3741.6 4378.9 4640.0 4698.7 13616.9 15382.6 15576.5 15602.5 15652.6 12604.2 15391.9 15506.3 15509.0 15524.0 8276.6 8596.8 8616.0 8616.0 8772.5 7126.6 7163.2 7227.6 7574.9 7649.8 13426.9 13762.7 14020.0 14043.8 14104.6 11634.6 14053.6 14150.2 14245.9 14259.1 9667.5 9797.0 9818.1 9990.9 9997.5 10897.4 10993.1 11019.2 11029.4 11044.0 9036.5 9102.0 9312.7 9518.3 9523.8 9151.5 9202.2 9279.2 9425.8 9492.9 7806.2 8380.7 8405.0 8468.2 8546.7 5931.2 6137.5 6181.7 6484.4 6573.8 6217.9 6227.2 6544.5 6685.1 6685.2 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5446.96 Median:5789.86 stddev:1640.36 (28.3%) stddev-sans-first:1599.74 10:37:21 INFO - PID 1647 | Values: 350.2 371.7 390.7 398.0 398.6 4878.1 5492.3 5968.8 6309.5 6359.3 5176.0 5610.9 6081.3 6084.0 6277.0 4792.7 4801.0 4811.5 4818.3 4840.3 3626.7 4782.0 4788.3 4832.2 4869.0 4333.8 4793.9 4801.6 4809.7 4813.0 4802.7 4803.2 4810.5 4817.5 4833.3 4267.4 4294.8 4299.1 4301.8 4302.9 5836.2 6137.9 6156.2 6203.5 6212.1 5057.2 5738.7 5746.0 5747.8 5789.0 5552.0 5634.7 6107.5 6447.9 6603.4 4316.7 4919.6 4928.3 4937.2 4941.6 5646.9 6789.2 7074.9 7119.4 7229.0 4313.4 4401.8 4925.1 4974.2 5033.3 4621.9 5119.4 5135.1 5147.8 5153.9 5475.0 5644.2 5698.6 5739.3 5748.0 5342.1 6457.9 6644.8 6669.5 6717.8 1501.6 1550.0 1690.9 1753.6 1760.2 1020.3 1058.4 1186.4 1222.7 1249.8 5080.7 5585.9 6216.8 6249.0 6271.1 6994.8 7008.7 7016.6 7026.7 7041.5 5104.9 5382.5 5428.8 6304.4 6343.2 3290.9 3648.6 3655.7 3659.0 3668.2 6763.4 7181.8 7193.5 7206.5 7210.4 6388.6 6476.7 6640.4 7091.8 7216.5 6501.1 6549.2 6568.1 7185.8 7217.0 6183.6 6948.8 6951.3 6984.9 6987.0 6801.5 6809.2 6843.8 6852.3 6868.7 6567.2 6734.2 6767.0 7161.6 7163.0 4200.3 4238.1 4576.4 4934.7 5022.4 6076.9 6963.2 6991.5 6998.4 7019.2 5406.3 6615.5 6689.6 6707.2 6750.7 5790.7 6451.7 6709.3 6724.0 6741.3 6194.4 6245.3 6558.7 6587.0 6614.8 6097.5 6678.2 6698.8 6713.0 6720.8 5498.6 5669.4 6509.4 6516.3 6526.6 10:37:21 INFO - PID 1647 | 10:37:21 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25898.27 Median:17958.60 stddev:39878.26 (222.1%) stddev-sans-first:39945.07 10:37:21 INFO - PID 1647 | Values: 649.6 770.5 791.6 801.9 873.1 16963.7 17415.3 17490.0 17506.1 17512.6 15180.5 15929.0 16259.5 16389.3 16415.3 907.4 919.9 926.3 927.0 927.7 801.8 931.1 947.9 956.2 957.6 977.4 1002.9 1008.9 1010.1 1014.8 997.4 1004.1 1004.3 1004.7 1008.7 729.6 746.8 748.5 748.8 751.9 17427.5 17664.3 17681.6 17844.3 17875.6 14920.4 17918.4 18257.3 18445.6 18578.4 14646.7 14746.8 14821.9 14832.2 14839.9 3512.0 3536.7 3541.4 3575.1 3577.9 134715.0 136478.0 137687.0 139329.3 140274.0 13157.3 13620.4 13738.4 13827.2 13924.0 25903.0 26407.3 26709.2 26721.9 26798.6 7719.7 7820.2 7837.0 7937.8 7960.5 3946.2 3991.9 4010.5 4013.9 4019.8 3644.5 4457.4 4493.9 4500.2 4521.5 917.7 952.3 964.3 964.4 966.1 638.7 651.6 666.7 672.5 673.9 11372.1 11722.0 11744.8 12029.4 12384.8 608.1 634.1 657.9 658.0 661.0 5381.2 5511.7 5580.4 5676.5 5815.2 147182.0 150226.0 150269.2 152065.0 153651.0 110485.0 123216.2 125206.7 132776.0 134228.3 97547.0 101307.5 102547.0 103760.0 104677.0 10326.9 10842.7 11882.8 11990.6 12364.0 33729.8 37747.8 40942.8 41760.0 44177.9 67841.0 86436.6 87068.6 87448.0 90002.5 16089.6 16706.4 17430.0 17478.1 17544.7 18623.9 18770.3 18979.7 18987.7 19050.6 16590.4 17877.4 17998.8 18062.4 18169.4 16431.5 16578.3 17233.7 17511.7 17904.0 16788.3 17388.6 17480.8 17497.6 17781.0 11461.7 11535.3 12038.5 12314.5 12520.9 12640.3 12832.3 13592.1 13631.4 13636.8 10:37:21 INFO - PID 1647 | -------- Summary: end -------- 10:37:21 INFO - PID 1647 | 10:37:21 INFO - TEST-INFO | 1647: exit 0 10:37:21 INFO - TEST-OK | dromaeo_css | took 1144014ms 10:37:21 INFO - TEST-START | kraken 10:37:21 INFO - operating with platform_type : mac_ 10:37:21 INFO - Initialising browser for kraken test... 10:37:21 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpOkU7MK/profile 10:37:21 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:37:55 INFO - TEST-INFO | 1658: killed by SIGTERM 10:37:55 INFO - Browser initialized. 10:37:55 INFO - Running cycle 1/1 for kraken test... 10:37:55 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/T/tmpOkU7MK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:37:57 INFO - PID 1662 | 2017-06-05 10:37:57.248 plugin-container[1663:10774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 10:37:57 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:37:57 INFO - PID 1662 | 2017-06-05 10:37:57.249 plugin-container[1663:10774] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b17, name = 'com.apple.CFPasteboardClient' 10:37:57 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:37:57 INFO - PID 1662 | 2017-06-05 10:37:57.249 plugin-container[1663:10774] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:37:58 INFO - PID 1662 | 2017-06-05 10:37:58.044 plugin-container[1664:10819] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:37:58 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:37:58 INFO - PID 1662 | 2017-06-05 10:37:58.045 plugin-container[1664:10819] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 10:37:58 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:37:58 INFO - PID 1662 | 2017-06-05 10:37:58.045 plugin-container[1664:10819] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:38:07 INFO - PID 1662 | RSS: Main: 259956736 10:38:07 INFO - PID 1662 | 10:38:09 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 10:38:10 INFO - PID 1662 | RSS: Main: 270573568 10:38:10 INFO - PID 1662 | 10:38:13 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 10:38:14 INFO - PID 1662 | RSS: Main: 281890816 10:38:14 INFO - PID 1662 | 10:38:17 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 10:38:18 INFO - PID 1662 | RSS: Main: 284221440 10:38:18 INFO - PID 1662 | 10:38:20 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 10:38:20 INFO - PID 1662 | RSS: Main: 285315072 10:38:20 INFO - PID 1662 | 10:38:22 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:38:23 INFO - PID 1662 | RSS: Main: 287096832 10:38:23 INFO - PID 1662 | 10:38:26 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 10:38:27 INFO - PID 1662 | RSS: Main: 291299328 10:38:27 INFO - PID 1662 | 10:38:29 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 10:38:30 INFO - PID 1662 | RSS: Main: 294481920 10:38:30 INFO - PID 1662 | 10:38:33 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 10:38:34 INFO - PID 1662 | RSS: Main: 293859328 10:38:34 INFO - PID 1662 | 10:38:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:38:36 INFO - PID 1662 | RSS: Main: 293847040 10:38:36 INFO - PID 1662 | 10:38:38 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:38:38 INFO - PID 1662 | RSS: Main: 293691392 10:38:38 INFO - PID 1662 | 10:38:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:38:41 INFO - PID 1662 | RSS: Main: 293650432 10:38:41 INFO - PID 1662 | 10:38:44 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:38:45 INFO - PID 1662 | RSS: Main: 293720064 10:38:45 INFO - PID 1662 | 10:38:48 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:38:49 INFO - PID 1662 | RSS: Main: 293593088 10:38:49 INFO - PID 1662 | 10:38:50 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 10:38:51 INFO - PID 1662 | RSS: Main: 295391232 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | __start_tp_report 10:38:51 INFO - PID 1662 | _x_x_mozilla_page_load 10:38:51 INFO - PID 1662 | _x_x_mozilla_page_load_details 10:38:51 INFO - PID 1662 | |i|pagename|runs| 10:38:51 INFO - PID 1662 | |0;ai-astar;104;115;114;111;113;116;115;114;116;115 10:38:51 INFO - PID 1662 | |1;audio-beat-detection;148;109;107;149;111;115;110;122;157;135 10:38:51 INFO - PID 1662 | |2;audio-dft;203;201;199;198;212;221;204;233;201;242 10:38:51 INFO - PID 1662 | |3;audio-fft;78;59;85;117;113;56;61;59;79;58 10:38:51 INFO - PID 1662 | |4;audio-oscillator;75;70;90;105;89;68;86;73;104;109 10:38:51 INFO - PID 1662 | |5;imaging-gaussian-blur;90;89;91;88;94;116;90;95;105;89 10:38:51 INFO - PID 1662 | |6;imaging-darkroom;99;98;97;97;99;96;97;96;98;98 10:38:51 INFO - PID 1662 | |7;imaging-desaturate;92;120;143;96;89;92;93;91;90;92 10:38:51 INFO - PID 1662 | |8;json-parse-financial;50;48;53;51;50;52;51;53;49;50 10:38:51 INFO - PID 1662 | |9;json-stringify-tinderbox;56;63;60;76;73;91;60;54;56;57 10:38:51 INFO - PID 1662 | |10;stanford-crypto-aes;87;99;109;108;87;86;87;91;117;94 10:38:51 INFO - PID 1662 | |11;stanford-crypto-ccm;137;144;128;149;138;154;135;133;175;154 10:38:51 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;185;183;187;188;221;174;227;171;176;232 10:38:51 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;58;68;69;66;66;67;62;68;69;68 10:38:51 INFO - PID 1662 | __end_tp_report 10:38:51 INFO - PID 1662 | __start_cc_report 10:38:51 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1194 10:38:51 INFO - PID 1662 | __end_cc_report 10:38:51 INFO - PID 1662 | __startTimestamp1496684331106__endTimestamp 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | ------- Summary: start ------- 10:38:51 INFO - PID 1662 | Number of tests: 14 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:3.59 (3.1%) stddev-sans-first:1.58 10:38:51 INFO - PID 1662 | Values: 104.0 115.0 114.0 111.0 113.0 116.0 115.0 114.0 116.0 115.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:128.50 stddev:19.21 (15.0%) stddev-sans-first:18.70 10:38:51 INFO - PID 1662 | Values: 148.0 109.0 107.0 149.0 111.0 115.0 110.0 122.0 157.0 135.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:211.40 Median:208.00 stddev:15.53 (7.5%) stddev-sans-first:16.17 10:38:51 INFO - PID 1662 | Values: 203.0 201.0 199.0 198.0 212.0 221.0 204.0 233.0 201.0 242.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:76.50 Median:60.00 stddev:22.78 (38.0%) stddev-sans-first:24.15 10:38:51 INFO - PID 1662 | Values: 78.0 59.0 85.0 117.0 113.0 56.0 61.0 59.0 79.0 58.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:74.00 stddev:15.28 (20.6%) stddev-sans-first:15.59 10:38:51 INFO - PID 1662 | Values: 75.0 70.0 90.0 105.0 89.0 68.0 86.0 73.0 104.0 109.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:90.00 stddev:8.99 (10.0%) stddev-sans-first:9.38 10:38:51 INFO - PID 1662 | Values: 90.0 89.0 91.0 88.0 94.0 116.0 90.0 95.0 105.0 89.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:98.00 stddev:1.08 (1.1%) stddev-sans-first:1.00 10:38:51 INFO - PID 1662 | Values: 99.0 98.0 97.0 97.0 99.0 96.0 97.0 96.0 98.0 98.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:92.00 stddev:17.66 (19.2%) stddev-sans-first:18.51 10:38:51 INFO - PID 1662 | Values: 92.0 120.0 143.0 96.0 89.0 92.0 93.0 91.0 90.0 92.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.64 (3.2%) stddev-sans-first:1.72 10:38:51 INFO - PID 1662 | Values: 50.0 48.0 53.0 51.0 50.0 52.0 51.0 53.0 49.0 50.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:61.50 stddev:11.83 (19.2%) stddev-sans-first:12.14 10:38:51 INFO - PID 1662 | Values: 56.0 63.0 60.0 76.0 73.0 91.0 60.0 54.0 56.0 57.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:87.00 stddev:11.22 (12.9%) stddev-sans-first:11.36 10:38:51 INFO - PID 1662 | Values: 87.0 99.0 109.0 108.0 87.0 86.0 87.0 91.0 117.0 94.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:146.50 stddev:13.84 (9.4%) stddev-sans-first:14.40 10:38:51 INFO - PID 1662 | Values: 137.0 144.0 128.0 149.0 138.0 154.0 135.0 133.0 175.0 154.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.40 Median:187.50 stddev:23.09 (12.3%) stddev-sans-first:24.24 10:38:51 INFO - PID 1662 | Values: 185.0 183.0 187.0 188.0 221.0 174.0 227.0 171.0 176.0 232.0 10:38:51 INFO - PID 1662 | 10:38:51 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:68.00 stddev:3.51 (5.2%) stddev-sans-first:2.18 10:38:51 INFO - PID 1662 | Values: 58.0 68.0 69.0 66.0 66.0 67.0 62.0 68.0 69.0 68.0 10:38:51 INFO - PID 1662 | -------- Summary: end -------- 10:38:51 INFO - PID 1662 | 10:38:52 INFO - TEST-INFO | 1662: exit 0 10:38:52 INFO - TEST-OK | kraken | took 90485ms 10:38:52 INFO - SUITE-END | took 1234s 10:38:52 INFO - Completed test suite (00:20:34) 10:38:52 INFO - javascript benchmark 10:38:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8085.14363213172, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3574.928501429971, 3583.1758695500016, 3597.9280414391696, 3637.4725664778607, 3640.726945479088, 4813.711177329358, 6114.0540674973245, 6264.874702505947, 6279.49764018878, 6289.905651415221, 5413.323334583177, 6134.049222370529, 6145.508359331254, 6147.323794382614, 6202.937970620293, 5316.9734151329185, 5378.273933019051, 5388.43421440749, 5393.0, 5404.108322126855, 5418.512333889949, 5497.752601132958, 5513.724313784315, 5555.2778138842095, 5557.499825015748, 5327.413984461706, 5455.8363249102595, 5479.6164268501225, 5528.226048353214, 5546.084895992157, 4413.713108647928, 5416.512513873731, 5425.294711687495, 5456.181572764117, 5491.780328786844, 5318.441563635801, 5370.7046112463795, 5412.83761487156, 5427.240186373911, 5443.836684899459, 6157.076438534211, 6206.844828879269, 6229.25248970122, 6248.187735594389, 6263.185785847856, 6237.407446292595, 6443.226812782496, 6522.804315870531, 6565.406664367596, 6602.7689030883685, 5590.776368945278, 6699.59802411852, 6721.227058888241, 6729.05793188954, 6745.932540674628, 6341.524385671093, 6361.777337793155, 6361.872762544724, 6375.8406039848915, 6376.553641245161, 6603.966980165068, 6695.698693558801, 6813.863722725518, 6816.216135144423, 6829.282925292872, 6364.681765911686, 6376.139221205078, 6417.807465776034, 6490.513211509061, 6545.607263564201, 6735.696893639798, 6741.561798483083, 6742.898856517156, 6761.0872532208505, 6769.187697476236, 5496.477834605706, 6570.441512471493, 6614.6361950093205, 6621.635810030494, 6624.138861948012, 5659.490645841894, 6449.193850768654, 6456.903146452807, 6480.4815614750705, 6650.20197576294, 5337.146056630998, 5349.866253343697, 5350.250964864922, 5351.652142610717, 5382.2464854920345, 4757.26262429324, 4768.809247630125, 4769.093872164278, 4801.975990120027, 4814.831480898151, 5072.9746351268, 6204.255489341218, 6270.090836828635, 6282.905756413657, 6376.872462550723, 5562.888742225133, 6728.7308507659145, 6740.39336459721, 6752.5948443093575, 6765.391114799691, 6193.998256755074, 6335.3981371769605, 6344.587601805868, 6347.428731414195, 6400.967995159995, 5775.0, 5818.709064546756, 5837.795677151279, 5934.673592952429, 5956.1065840123465, 6891.517593768488, 6927.7228910845015, 6932.896006559805, 6950.687219075053, 6957.060796792571, 6766.627835469097, 6767.560108592926, 6776.186857577122, 6778.152730908637, 6795.048693182859, 6626.602403855784, 6803.251642319242, 6941.444684425335, 6949.0, 6962.408195303456, 6304.65324407162, 6329.30377658448, 6357.396047375492, 6365.458935990493, 6413.551051426355, 6762.289959554176, 6774.288699686462, 6781.152355955505, 6783.084283621648, 6819.659017049227, 6370.426661600478, 6416.230052393743, 6434.131392261985, 6502.219733631994, 6509.674516274073, 6453.870922581428, 6469.676516174267, 6503.0, 6516.446102081376, 6521.315261897433, 6339.90490142639, 6384.457321127757, 6406.87186256263, 6407.519436042222, 6421.967890160519, 6360.045993101072, 6385.68071596409, 6486.0, 6500.479961603155, 6532.0, 6305.716242768995, 6336.334684858023, 6369.7452101915405, 6381.170447841937, 6395.872082558416], "value": 6060.517076191832, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.8220209351849, 816.7549735079476, 826.9751907442775, 1257.0886107572005, 1319.122783349073, 38374.23251534968, 39328.62349817761, 39474.802625986864, 39478.80260598693, 39597.20805583886, 39628.20743585127, 39679.61121360753, 39688.60311396885, 39759.40360894589, 39850.800745996305, 6659.4672426206025, 7682.308592226685, 8117.107118216992, 8296.128906464824, 8325.292350150245, 8219.095899451055, 8257.669693212265, 8364.121767214447, 8378.204070613281, 8396.202360775716, 8312.04411492677, 8370.58147092646, 8387.83224335513, 8401.285890699299, 8424.578771061453, 8291.253787159125, 8354.791130221733, 8371.246587807096, 8387.538685372301, 8408.327333813342, 6529.477641788645, 8124.878126828103, 8129.146439623813, 8142.470739401979, 8145.755627331189, 36734.265314693825, 36992.44511332332, 37245.0, 37315.62684373149, 37390.878273651564, 3434.5019972104155, 3464.4630082337276, 3470.8785192518394, 3475.322312149132, 3476.756627036083, 30396.392072158433, 30419.23951901198, 30454.0, 30497.0, 30555.08334749961, 3096.674849140853, 3097.4579448596633, 3107.8446077696026, 3111.2688518198206, 3121.547375630521, 254176.0, 255740.0, 289698.9999999979, 316121.0, 316360.2545961812, 47123.764381177876, 47892.5210747895, 48001.519984800405, 48044.79888002828, 48162.0, 50462.0, 50463.0, 50469.74765126151, 50661.240081398806, 54627.72686136544, 14229.28853557318, 14286.21425821569, 14335.426582936774, 14388.424463021362, 14432.206228657315, 11433.970942615204, 11505.0, 11526.59656912004, 11560.248583842023, 11597.014253788522, 13865.23741194244, 13974.301284935713, 14145.939054570948, 14158.29208539569, 14265.286735663176, 5558.722063896789, 5915.64506129625, 6172.783952561639, 6196.0, 6201.224846894138, 963.6001059560344, 964.4743614729989, 966.8259713251541, 1010.3786171504619, 1013.8124446977333, 19434.80565194358, 21954.824947876816, 22389.32832015042, 22962.65556016661, 23623.527529449315, 836.4228682209256, 980.3676628574672, 1029.130384824822, 1085.4898197846999, 1119.3004372267333, 9334.0, 11230.3261804292, 11554.248973816675, 11624.418779061014, 12124.030077593772, 283908.0, 285068.0, 285997.0, 287195.6402179883, 287467.0, 55715.44284557184, 55896.72051639716, 55915.0, 55985.44014559884, 56176.71911640416, 1426.3866537389022, 1456.1772598482037, 1485.814273215848, 1495.7307684616655, 1503.2784263553556, 22009.889950550787, 22272.554548908607, 22851.88574057119, 23036.4240893979, 23673.64489532693, 50444.24333634925, 51827.74086129545, 51903.74048129886, 51907.44277671676, 52049.739751301, 289761.0, 292213.9282339492, 292356.0, 292568.53715731285, 292993.0, 34557.65442345645, 36105.0973725659, 36243.45634815533, 36541.634583653824, 36761.0, 43577.0, 43637.78181109074, 46457.30314045224, 46752.532474674816, 46790.0, 28355.858220708764, 29744.55383169211, 30774.38451230918, 31262.37475250437, 31724.524132138496, 30018.549721753756, 31307.84346078255, 31553.684463155994, 31722.841385793847, 31786.205344866565, 31324.373512530077, 38252.426213607374, 38295.2340953185, 38296.0, 38296.42555361616, 30082.849585752807, 30319.090427286334, 30462.23844403819, 31661.67552406964, 32373.51439728453, 22280.0, 24103.63844542298, 24467.0, 24524.0, 24590.139345122836], "value": 17502.14064703095, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.7769382982551, 389.7875657766517, 411.7755823076425, 424.2215534494203, 449.2721790699068, 21864.0, 22636.54726905463, 22698.319050428472, 23321.0, 24451.75548244517, 24289.878550607224, 24812.379690507747, 24829.255122346312, 24904.0, 24922.626160607604, 5497.202905578689, 5555.000099982001, 5838.41615838416, 6255.12428260044, 6269.592476489036, 5390.299261096052, 6083.365376452546, 6221.937780622181, 6237.157983672214, 6258.906116408235, 6111.602745821529, 6193.969030154843, 6203.596766210205, 6205.193324867761, 6209.720562574695, 5422.53908417785, 6073.939260607403, 6186.84532886677, 6194.0, 6214.285357183891, 5453.345598528202, 5496.917546236769, 5509.862253443696, 5510.889782204334, 5515.944840551583, 28226.717732822613, 28843.711562884313, 29160.416791664262, 29421.970231042025, 29581.55627665587, 2177.292379976514, 2248.718910136233, 2252.414372263207, 2259.1754009786473, 2266.0, 18759.437216883514, 18781.436556903314, 18960.81039189618, 19061.856288622726, 19076.71384929227, 2053.5276886316083, 2105.4420578546697, 2160.82713382929, 2186.7922547357975, 2187.453136715821, 109791.0, 157368.0, 169207.0, 169966.0, 187933.0, 10842.512086956105, 12848.614541563767, 12991.0, 13197.142185757895, 13343.73312533744, 13736.93131534356, 16202.918985405233, 16689.582760431087, 16705.081220532986, 16762.413315533897, 9050.818983620291, 9083.227925626263, 9115.225205857443, 9264.44413335202, 9378.67174648884, 8982.505962172012, 9009.279257659371, 9044.095590440904, 9046.638134474679, 9061.818763624691, 7029.507934444642, 8318.584070796436, 8396.412251142483, 8443.240108390275, 8499.0, 2250.27991042868, 2252.549490101986, 2259.469394278419, 2262.8755418452142, 2444.902203911859, 912.748994026648, 912.9680461183959, 933.6872147830417, 950.4772375193616, 971.3540495570367, 18482.260709571736, 20521.89739051325, 21805.67291490629, 22005.66991495129, 22082.558348833256, 960.2989817433313, 963.8987906269881, 1001.3040936549029, 1006.4773293472845, 1015.4212099103588, 10990.505427255634, 11341.773164536828, 11375.317480951004, 11454.885451145548, 11570.479328430072, 461530.0, 480590.5970470195, 515415.0, 536162.0, 552125.0, 138680.0, 139363.0, 140116.0, 142623.0, 148393.0, 911.3893691226893, 950.9809803804023, 1132.224426268009, 1167.5971789732528, 1167.8481797366287, 21487.78512214858, 23382.64926026077, 23509.294721158392, 23554.173499180208, 23589.0, 61093.69453152706, 63777.0, 67681.3231867675, 68157.0, 70032.0, 78324.0, 79312.60343698245, 88028.0, 88437.23125537582, 89110.10889891277, 10110.646127385506, 10203.84694229602, 10252.846207307046, 10267.178625710074, 10296.639617613348, 16391.262393192574, 17261.482155535356, 18360.72458913144, 18399.264029438673, 18466.0, 14897.851021489647, 14987.100773953598, 15036.398544058115, 15112.773308400163, 15214.847851521343, 11664.950154486138, 12912.418941147484, 13963.301834908418, 14000.789988150389, 14155.079919805179, 15598.220088995278, 15633.76549351738, 15781.68436631238, 15849.762253565976, 15908.761368579711, 10353.171746260432, 10381.325213861075, 10421.635242766466, 10451.163906887583, 10474.633387831389, 15052.0, 15168.544943651708, 15195.0, 15313.923430382776, 15318.157501337533], "value": 11111.413664837135, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1209.8366720492736, 1380.6962468256984, 1432.6561625209952, 1450.2748625687157, 1461.2255504582572, 11592.826107608393, 11676.766464670702, 12223.205491643037, 12230.38848057597, 12346.0, 11878.584249551268, 12016.699582510442, 12076.09429292802, 12091.637250882466, 12105.636830895088, 2506.636537702032, 2548.5412625727363, 2571.742825717432, 2576.3687896465394, 2577.2397142842906, 2091.665333546633, 2186.125549780085, 2470.1848390031246, 2503.4116982509095, 2545.7454254574486, 2515.4466017476125, 2519.7732204101626, 2543.504016716741, 2544.6183072539084, 2545.681789776278, 2395.772401621843, 2416.7704068113503, 2416.903323867043, 2418.1415597462824, 2429.671994280767, 2483.615039668854, 2501.062101711858, 2501.137107697841, 2527.772500474966, 2545.121932933135, 11944.402779861059, 12071.215371000857, 12094.697632559168, 12104.273743575413, 12110.939445302716, 6462.386073323027, 6673.165854268216, 6702.430293425016, 6722.0253063305545, 6725.831854203635, 3788.8863334099747, 3985.70107241958, 4213.5154457237195, 4252.787360631987, 4289.420928174688, 1267.4803330634395, 1272.872712728729, 1279.1429742072833, 1303.283194243172, 1315.3291821171176, 13742.175469471864, 16484.34062637493, 16554.668906622042, 16593.336266549322, 16870.325186992504, 5476.972615136979, 5545.251391062236, 5631.465010823965, 5742.28221472316, 5744.885102298014, 12085.154039217347, 12234.449449774782, 12341.0, 12341.444634991267, 12402.75194496105, 6448.097266382711, 6453.354664533509, 6540.901886471612, 6541.574797638234, 6558.114654521674, 6133.754649813957, 6192.349803270682, 6193.349698281616, 6202.379762023852, 6205.22434695663, 4336.02439451121, 4411.580899814512, 4425.159219748302, 4445.73325600465, 4521.751303678264, 1172.3317708905843, 1204.855417349906, 1273.726148877977, 1285.4408332375447, 1320.3134370127673, 816.391788117856, 818.2962652119173, 839.3369238301796, 850.9319254459629, 876.8334016536837, 6639.0, 6892.827679308058, 7302.8174295641975, 7315.305046020726, 7412.407007439392, 925.5418567808978, 961.9230461563059, 966.5070813884969, 974.1573538888952, 1000.7898341348252, 3669.064388580948, 3741.569719482294, 4378.934315985262, 4639.976800115978, 4698.694584852042, 13616.863831361758, 15382.615434614225, 15576.532704018897, 15602.453914112952, 15652.608684782745, 12604.180728252633, 15391.923040384727, 15506.302216400063, 15508.991915525456, 15524.0, 8276.586170691442, 8596.828063438696, 8615.956920215483, 8616.0, 8772.517511536927, 7126.643667816485, 7163.247858974941, 7227.5611258296585, 7574.886376704244, 7649.770506884803, 13426.865731342952, 13762.724745505235, 14020.018598697994, 14043.789343159657, 14104.576862694135, 11634.592789252336, 14053.648658783202, 14150.221737804519, 14245.857541424453, 14259.144451332953, 9667.516624168904, 9797.0, 9818.067283608047, 9990.900090999288, 9997.450140242356, 10897.400642964712, 10993.120550355792, 11019.228653994143, 11029.44852757343, 11044.0, 9036.45781253127, 9102.0, 9312.72061838146, 9518.286128540249, 9523.809523809623, 9151.496667683075, 9202.171804537624, 9279.164875161268, 9425.764355891157, 9492.85760713576, 7806.1803510632835, 8380.706675266336, 8404.991401031915, 8468.237858592756, 8546.743597692079, 5931.169636250842, 6137.539684523589, 6181.721822517908, 6484.448821850006, 6573.835654108686, 6217.862204743573, 6227.221597300338, 6544.509161812788, 6685.130932978682, 6685.197776266879], "value": 5485.300878533177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.1613635343353, 371.71006614840434, 390.7147782119053, 398.0367510624289, 398.64321432318076, 4878.121938051149, 5492.286002819638, 5968.8360769649935, 6309.526785491095, 6359.300476947532, 5175.974120129404, 5610.905873354697, 6081.331053584102, 6083.965725826609, 6276.968615156918, 4792.736399498026, 4800.967791924735, 4811.518848115178, 4818.301346304793, 4840.273958906157, 3626.673599376056, 4781.952180478185, 4788.305695674125, 4832.154372984731, 4868.951310486903, 4333.848315308965, 4793.880152996186, 4801.567858892699, 4809.663323567352, 4812.975935120319, 4802.687825291362, 4803.207470767319, 4810.542998415145, 4817.494163112875, 4833.32333473314, 4267.445232119804, 4294.806733696991, 4299.097189590189, 4301.784910754481, 4302.913941721148, 5836.212111364954, 6137.925862974006, 6156.168917196166, 6203.47270482005, 6212.1303017577575, 5057.216131499624, 5738.684372359518, 5746.02317606008, 5747.827565173052, 5788.97105514474, 5552.0, 5634.690092044936, 6107.465012436694, 6447.903281450782, 6603.405693487609, 4316.661834831181, 4919.581835543947, 4928.285398617216, 4937.1606826839525, 4941.555260026615, 5646.858828529279, 6789.18529776425, 7074.893876591804, 7119.359257666835, 7229.0, 4313.374560688682, 4401.779911004437, 4925.064237794808, 4974.1543937530705, 5033.295338652591, 4621.930671039937, 5119.385673719177, 5135.101357262464, 5147.7683504242395, 5153.948460515347, 5475.0, 5644.181593668895, 5698.601097923106, 5739.311282646109, 5747.971260143672, 5342.1185504392115, 6457.902156633384, 6644.833879152963, 6669.499787515956, 6717.764878229238, 1501.617087642666, 1549.9612509687122, 1690.8562772164503, 1753.587906841891, 1760.2079064421062, 1020.3061917895902, 1058.4072919165292, 1186.3890096600257, 1222.6821026533166, 1249.8000319948947, 5080.695158290514, 5585.8603534911135, 6216.818804427235, 6249.0, 6271.133109245369, 6994.825129371807, 7008.684609192598, 7016.614086225204, 7026.683799229047, 7041.5422997505, 5104.897902041938, 5382.528269266301, 5428.8099916503525, 6304.432601065926, 6343.238811342669, 3290.8646516951612, 3648.5986541480415, 3655.670989610948, 3659.0, 3668.220912857262, 6763.391294783395, 7181.820454488575, 7193.5324203926575, 7206.495545311778, 7210.387117095001, 6388.648624325707, 6476.708548115346, 6640.402363787186, 7091.787246382617, 7216.458765592602, 6501.0573466847045, 6549.2468366137555, 6568.0772056434425, 7185.784426467214, 7216.96391518039, 6183.5671502994355, 6948.826279343057, 6951.339622735832, 6984.895226571504, 6986.9650651746415, 6801.455883529405, 6809.182898052265, 6843.794686159423, 6852.314768523307, 6868.656567171522, 6567.2447668516825, 6734.191896972395, 6766.966165169143, 7161.641917904188, 7162.964185179041, 4200.264953633041, 4238.131183107394, 4576.359309696579, 4934.679245849008, 5022.397312322545, 6076.939230607637, 6963.199232088375, 6991.545549539467, 6998.440124790106, 7019.227884932756, 5406.297181366397, 6615.4530505754665, 6689.632070236184, 6707.228668702966, 6750.729970801113, 5790.741610771886, 6451.7419103274415, 6709.329067093252, 6724.007816521601, 6741.325867413416, 6194.38056194377, 6245.344238854855, 6558.688262347454, 6586.99096775488, 6614.801555953329, 6097.4512293891985, 6678.23200331968, 6698.799036028732, 6712.966435167793, 6720.8319792005595, 5498.615096943176, 5669.404712505293, 6509.381608747161, 6516.315786842313, 6526.608403495806], "value": 4937.401425542609, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.5972497051827, 770.4991755359019, 791.5962858941941, 801.9157988411221, 873.0570983337993, 16963.66072678547, 17415.3033878645, 17489.950602963814, 17506.124693765323, 17512.562185945357, 15180.466561121922, 15928.964617299867, 16259.51221463358, 16389.262483188257, 16415.261313240902, 907.3693782820942, 919.9448033118001, 926.3006430145265, 926.9942112807612, 927.6660402255183, 801.7594721583531, 931.1340453378356, 947.9336446448751, 956.2493442647523, 957.6265256549916, 977.4233202410576, 1002.91475224606, 1008.8940661230539, 1010.0757806606946, 1014.7919676466344, 997.4065431068502, 1004.0712341084504, 1004.2518323848707, 1004.688546550568, 1008.6570566007556, 729.6351824087956, 746.8095635612884, 748.4910261022502, 748.8056549803056, 751.9248075192492, 17427.47717568475, 17664.293428262856, 17681.55796105095, 17844.28622855084, 17875.642487150184, 14920.403183872631, 17918.372856950074, 18257.269709211614, 18445.63108737831, 18578.44264672062, 14646.707065858729, 14746.829431672242, 14821.925890370587, 14832.184229867353, 14839.851601483955, 3511.964880351215, 3536.664016918389, 3541.415666415064, 3575.0704816747575, 3577.874774382884, 134715.0, 136477.999999999, 137687.0, 139329.30335348358, 140274.0, 13157.276349800853, 13620.387082581308, 13738.381772820247, 13827.170369777645, 13924.0, 25903.0, 26407.33981650474, 26709.19872403831, 26721.86639066831, 26798.5980210297, 7719.65261563231, 7820.217978202248, 7836.962389778699, 7937.841243175104, 7960.4825686330205, 3946.1910308386714, 3991.940120898189, 4010.5187377514885, 4013.9397909031386, 4019.8191081401405, 3644.526211592476, 4457.353683715908, 4493.865184044484, 4500.167469018176, 4521.4574251089425, 917.7155081924491, 952.3381250031161, 964.3153361113688, 964.3876138652007, 966.1256562810527, 638.7030031035498, 651.5960104735, 666.6766618190173, 672.4855485553555, 673.9494537909676, 11372.090232781358, 11722.0, 11744.765104697859, 12029.398530073462, 12384.75230495385, 608.1243010065491, 634.1375729008545, 657.8782925158861, 658.0228360884083, 661.048090749329, 5381.19282107687, 5511.724413779295, 5580.414056524088, 5676.545876329967, 5815.156802263648, 147182.0, 150226.0, 150269.24865375823, 152065.0, 153651.0, 110485.0, 123216.15175772371, 125206.7479325195, 132776.0, 134228.32885835704, 97547.0, 101307.49346253369, 102547.0, 103760.0, 104677.0, 10326.948365258126, 10842.674719758419, 11882.821757673815, 11990.640280791591, 12364.0, 33729.83135084309, 37747.81126094352, 40942.79528602338, 41759.956001099, 44177.895552611444, 67841.0, 86436.56781716051, 87068.5646571763, 87448.0, 90002.54998725226, 16089.597760055623, 16706.4152754653, 17430.041347725997, 17478.12609369552, 17544.736828947833, 18623.906880466053, 18770.343037993604, 18979.71530427072, 18987.715184271972, 19050.618987620448, 16590.419335323204, 17877.37429189972, 17998.820011799715, 18062.36781712634, 18169.36407225741, 16431.507054788377, 16578.336866525206, 17233.655326893644, 17511.737323939895, 17904.0, 16788.328466861676, 17388.565285867953, 17480.82519174792, 17497.56256093557, 17781.0, 11461.656150315504, 11535.250208736405, 12038.518459261883, 12314.50741970347, 12520.937395312965, 12640.30478323701, 12832.294223817406, 13592.11651242666, 13631.386587603383, 13636.795448068486], "value": 8750.388861780855, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 111.0, 113.0, 116.0, 115.0, 114.0, 116.0, 115.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 109.0, 107.0, 149.0, 111.0, 115.0, 110.0, 122.0, 157.0, 135.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 201.0, 199.0, 198.0, 212.0, 221.0, 204.0, 233.0, 201.0, 242.0], "value": 211.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 85.0, 117.0, 113.0, 56.0, 61.0, 59.0, 79.0, 58.0], "value": 76.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 90.0, 105.0, 89.0, 68.0, 86.0, 73.0, 104.0, 109.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 88.0, 94.0, 116.0, 90.0, 95.0, 105.0, 89.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 97.0, 97.0, 99.0, 96.0, 97.0, 96.0, 98.0, 98.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 120.0, 143.0, 96.0, 89.0, 92.0, 93.0, 91.0, 90.0, 92.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 53.0, 51.0, 50.0, 52.0, 51.0, 53.0, 49.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 60.0, 76.0, 73.0, 91.0, 60.0, 54.0, 56.0, 57.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 99.0, 109.0, 108.0, 87.0, 86.0, 87.0, 91.0, 117.0, 94.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 144.0, 128.0, 149.0, 138.0, 154.0, 135.0, 133.0, 175.0, 154.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 187.0, 188.0, 221.0, 174.0, 227.0, 171.0, 176.0, 232.0], "value": 194.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 68.0, 69.0, 66.0, 66.0, 67.0, 62.0, 68.0, 69.0, 68.0], "value": 66.1, "unit": "score"}]}]} 10:38:52 INFO - Return code: 0 10:38:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:38:52 INFO - # TBPL SUCCESS # 10:38:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:38:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:38:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:38:52 INFO - 2017-06-05 10:38:52.500 screenresolution[1670:11204] starting screenresolution argv=screenresolution get 10:38:52 INFO - 2017-06-05 10:38:52.503 screenresolution[1670:11204] Display 0: 1600x1200x32@60 10:38:52 INFO - 2017-06-05 10:38:52.509 screenresolution[1671:11207] starting screenresolution argv=screenresolution list 10:38:52 INFO - Available Modes on Display 0 10:38:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:38:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:38:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:38:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:38:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:38:52 INFO - Intel Iris: 10:38:52 INFO - Chipset Model: Intel Iris 10:38:52 INFO - Type: GPU 10:38:52 INFO - Bus: Built-In 10:38:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:38:52 INFO - Vendor: Intel (0x8086) 10:38:52 INFO - Device ID: 0x0a2e 10:38:52 INFO - Revision ID: 0x0009 10:38:52 INFO - Displays: 10:38:52 INFO - Display: 10:38:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:38:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:38:52 INFO - Display Serial Number: 1600x1200 60 10:38:52 INFO - Main Display: Yes 10:38:52 INFO - Mirror: Off 10:38:52 INFO - Online: Yes 10:38:52 INFO - Rotation: Supported 10:38:52 INFO - Return code: 0 10:38:52 INFO - Running post-action listener: _resource_record_post_action 10:38:52 INFO - [mozharness: 2017-06-05 17:38:52.749846Z] Finished run-tests step (success) 10:38:52 INFO - Running post-run listener: _resource_record_post_run 10:38:52 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 50027520; Write bytes: 572424192; Read time: 550; Write time: 1920 10:38:52 INFO - TinderboxPrint: CPU usage
53.3% 10:38:52 INFO - TinderboxPrint: I/O read bytes / time
50,027,520 / 550 10:38:52 INFO - TinderboxPrint: I/O write bytes / time
572,424,192 / 1,920 10:38:52 INFO - TinderboxPrint: CPU idle
2,346.4 (46.7%) 10:38:52 INFO - TinderboxPrint: CPU system
1,020.9 (20.3%) 10:38:52 INFO - TinderboxPrint: CPU user
1,662.4 (33.1%) 10:38:52 INFO - TinderboxPrint: Swap in / out
288,591,872 / 0 10:38:52 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227117568; Write bytes: 223868928; Read time: 16724; Write time: 543 10:38:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:38:52 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 48999424; Write bytes: 320202752; Read time: 532; Write time: 1305 10:38:53 INFO - Running post-run listener: _upload_blobber_files 10:38:53 INFO - Blob upload gear active. 10:38:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:38:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:38:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:38:53 INFO - (blobuploader) - INFO - Open directory for files ... 10:38:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:38:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:38:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:54 INFO - (blobuploader) - INFO - Done attempting. 10:38:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:38:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:38:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:55 INFO - (blobuploader) - INFO - Done attempting. 10:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:38:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:56 INFO - (blobuploader) - INFO - Done attempting. 10:38:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:38:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:38:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:38:58 INFO - (blobuploader) - INFO - Done attempting. 10:38:58 INFO - (blobuploader) - INFO - Iteration through files over. 10:38:58 INFO - Return code: 0 10:38:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:38:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:38:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0599a2106fa52f68f7ea19c407d8938f40f6f2bff826605940d50e831c0bf031842675d971b607a5cdcd795287ba15e2d783101fa4a4970d1f077c0412b917fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0402e3507c575d259240ad1e6e8ce48629953688eea468fd61857e79decfee8fbabe0b272ba5e82c4a3d6748e7b22396cec16aa5eb7bd59a60791cfcc38f76e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bffff8178a8cbfa0ace64004300b76c852aacb14073a195de00088d4b8021e006ba8c6b8b3b55ddcb622a38529c722981a878865f030774c72d4e2fba481851b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f16d0c35b64514112740a015c28d50d505e9b779beb643a52d63dad6443108df859a75fb428a1c2cebc9df79d2bee3bb9acff206eb698727db58bf9427e51e"} 10:38:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:38:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:38:58 INFO - Contents: 10:38:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0599a2106fa52f68f7ea19c407d8938f40f6f2bff826605940d50e831c0bf031842675d971b607a5cdcd795287ba15e2d783101fa4a4970d1f077c0412b917fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0402e3507c575d259240ad1e6e8ce48629953688eea468fd61857e79decfee8fbabe0b272ba5e82c4a3d6748e7b22396cec16aa5eb7bd59a60791cfcc38f76e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bffff8178a8cbfa0ace64004300b76c852aacb14073a195de00088d4b8021e006ba8c6b8b3b55ddcb622a38529c722981a878865f030774c72d4e2fba481851b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f16d0c35b64514112740a015c28d50d505e9b779beb643a52d63dad6443108df859a75fb428a1c2cebc9df79d2bee3bb9acff206eb698727db58bf9427e51e"} 10:38:58 INFO - Running post-run listener: copy_logs_to_upload_dir 10:38:58 INFO - Copying logs to upload dir... 10:38:58 INFO - mkdir: /builds/slave/test/build/upload/logs 10:38:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.545950 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-05 10:38:58.651095) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 10:38:58.655498) ========= 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.WFpl6xj28m/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.i46JiFL1hm/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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/0599a2106fa52f68f7ea19c407d8938f40f6f2bff826605940d50e831c0bf031842675d971b607a5cdcd795287ba15e2d783101fa4a4970d1f077c0412b917fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0402e3507c575d259240ad1e6e8ce48629953688eea468fd61857e79decfee8fbabe0b272ba5e82c4a3d6748e7b22396cec16aa5eb7bd59a60791cfcc38f76e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bffff8178a8cbfa0ace64004300b76c852aacb14073a195de00088d4b8021e006ba8c6b8b3b55ddcb622a38529c722981a878865f030774c72d4e2fba481851b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f16d0c35b64514112740a015c28d50d505e9b779beb643a52d63dad6443108df859a75fb428a1c2cebc9df79d2bee3bb9acff206eb698727db58bf9427e51e"} build_url:https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/ZPkBWxRsRumKxQB5ApcGNQ/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/0599a2106fa52f68f7ea19c407d8938f40f6f2bff826605940d50e831c0bf031842675d971b607a5cdcd795287ba15e2d783101fa4a4970d1f077c0412b917fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0402e3507c575d259240ad1e6e8ce48629953688eea468fd61857e79decfee8fbabe0b272ba5e82c4a3d6748e7b22396cec16aa5eb7bd59a60791cfcc38f76e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bffff8178a8cbfa0ace64004300b76c852aacb14073a195de00088d4b8021e006ba8c6b8b3b55ddcb622a38529c722981a878865f030774c72d4e2fba481851b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22f16d0c35b64514112740a015c28d50d505e9b779beb643a52d63dad6443108df859a75fb428a1c2cebc9df79d2bee3bb9acff206eb698727db58bf9427e51e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 10:38:58.689826) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 10:38:58.690185) ========= 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.WFpl6xj28m/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.i46JiFL1hm/Listeners TMPDIR=/var/folders/yg/ccdgyw0j4yvb_ynprnv7y9t800000w/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.005217 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 10:38:58.739505) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 10:38:58.739831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 10:38:58.740190) ========= ========= Total master_lag: 0.06 =========