builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1496967736.64 results: success (0) revision: 5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:16.643178) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:16.643836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:16.644138) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:16.669110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:16.669405) ========= 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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-08 17:22:16-- 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% 17.1M=0.001s 2017-06-08 17:22:17 (17.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.484628 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:17.205117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:17.205478) ========= 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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.020915 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:17.254774) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 17:22:17.255100) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705 --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 5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ppYL4LTgah/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.9Oiqm5ytsi/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-08 17:22:17,329 truncating revision to first 12 chars 2017-06-08 17:22:17,329 Setting DEBUG logging. 2017-06-08 17:22:17,329 attempt 1/10 2017-06-08 17:22:17,329 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5c61d5d5fc65?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 17:22:18,050 unpacking tar archive at: mozilla-central-5c61d5d5fc65/testing/mozharness/ program finished with exit code 0 elapsedTime=0.975689 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 17:22:18.278732) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:18.279043) ========= script_repo_revision: 5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:18.279434) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:18.279703) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 17:22:18.306412) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-08 17:22:18.306705) ========= /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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:22:18 INFO - MultiFileLogger online at 20170608 17:22:18 in /builds/slave/test 17:22:18 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 17:22:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:22:18 INFO - {'append_to_log': False, 17:22:18 INFO - 'base_work_dir': '/builds/slave/test', 17:22:18 INFO - 'blob_upload_branch': 'Firefox', 17:22:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:22:18 INFO - 'branch': 'Firefox', 17:22:18 INFO - 'buildbot_json_path': 'buildprops.json', 17:22:18 INFO - 'config_files': ('talos/mac_config.py',), 17:22:18 INFO - 'default_actions': ('clobber', 17:22:18 INFO - 'read-buildbot-config', 17:22:18 INFO - 'download-and-extract', 17:22:18 INFO - 'populate-webroot', 17:22:18 INFO - 'create-virtualenv', 17:22:18 INFO - 'install', 17:22:18 INFO - 'setup-mitmproxy', 17:22:18 INFO - 'run-tests'), 17:22:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:22:18 INFO - 'download_minidump_stackwalk': True, 17:22:18 INFO - 'download_symbols': 'ondemand', 17:22:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:22:18 INFO - 'tooltool.py': '/tools/tooltool.py', 17:22:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:22:18 INFO - '/tools/misc-python/virtualenv.py')}, 17:22:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:22:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:22:18 INFO - 'gecko_profile': False, 17:22:18 INFO - 'gecko_profile_interval': 0, 17:22:18 INFO - 'installer_path': 'installer.exe', 17:22:18 INFO - 'log_level': 'info', 17:22:18 INFO - 'log_name': 'talos', 17:22:18 INFO - 'log_to_console': True, 17:22:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:22:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:22:18 INFO - 'opt_config_files': (), 17:22:18 INFO - 'pip_index': False, 17:22:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:22:18 INFO - 'cmd': ('bash', 17:22:18 INFO - '-c', 17:22:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:22:18 INFO - 'enabled': True, 17:22:18 INFO - 'halt_on_failure': False, 17:22:18 INFO - 'name': 'check_screen_resolution'},), 17:22:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:22:18 INFO - 'cmd': ('bash', 17:22:18 INFO - '-c', 17:22:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:22:18 INFO - 'enabled': True, 17:22:18 INFO - 'halt_on_failure': False, 17:22:18 INFO - 'name': 'check_screen_resolution'},), 17:22:18 INFO - 'run_cmd_checks_enabled': True, 17:22:18 INFO - 'suite': 'dromaeojs-e10s', 17:22:18 INFO - 'system_bits': '32', 17:22:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:22:18 INFO - 'title': 't-yosemite-r7-0111', 17:22:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:22:18 INFO - 'use_talos_json': True, 17:22:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:22:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:22:18 INFO - 'work_dir': 'build'} 17:22:18 INFO - [mozharness: 2017-06-09 00:22:18.511369Z] Running clobber step. 17:22:18 INFO - Running pre-action listener: _resource_record_pre_action 17:22:18 INFO - Running main action method: clobber 17:22:18 INFO - rmtree: /builds/slave/test/build 17:22:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:22:20 INFO - Running post-action listener: _resource_record_post_action 17:22:20 INFO - [mozharness: 2017-06-09 00:22:20.474202Z] Finished clobber step (success) 17:22:20 INFO - [mozharness: 2017-06-09 00:22:20.474334Z] Running read-buildbot-config step. 17:22:20 INFO - Running pre-action listener: _resource_record_pre_action 17:22:20 INFO - Running main action method: read_buildbot_config 17:22:20 INFO - Using buildbot properties: 17:22:20 INFO - { 17:22:20 INFO - "project": "", 17:22:20 INFO - "product": "firefox", 17:22:20 INFO - "who": "kwierso@gmail.com", 17:22:20 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:22:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:22:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:22:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:22:20 INFO - "stage_platform": "macosx64", 17:22:20 INFO - "basedir": "/builds/slave/test", 17:22:20 INFO - "buildnumber": 43, 17:22:20 INFO - "platform": "yosemite_r7", 17:22:20 INFO - "parent_task_id": "bHvIlpttSMqv9HQj20HzIA", 17:22:20 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:22:20 INFO - "slavebuilddir": "test", 17:22:20 INFO - "taskId": "SpqpQmw_QbOvtJQJZKfUuQ", 17:22:20 INFO - "branch": "mozilla-central", 17:22:20 INFO - "script_repo_revision": "production", 17:22:20 INFO - "revision": "5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705", 17:22:20 INFO - "slavename": "t-yosemite-r7-0111", 17:22:20 INFO - "repo_path": "mozilla-central" 17:22:20 INFO - } 17:22:20 INFO - Finding installer, test and symbols from parent task. 17:22:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SpqpQmw_QbOvtJQJZKfUuQ'}, attempt #1 17:22:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SpqpQmw_QbOvtJQJZKfUuQ'}, attempt #1 17:22:21 INFO - Task dependencies: Yx4SAXK6Tf-DAmr3IOP7TA bHvIlpttSMqv9HQj20HzIA 17:22:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA'}, attempt #1 17:22:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:22:22 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/test_packages.json 17:22:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/target.crashreporter-symbols.zip 17:22:22 INFO - Running post-action listener: _resource_record_post_action 17:22:22 INFO - [mozharness: 2017-06-09 00:22:22.204186Z] Finished read-buildbot-config step (success) 17:22:22 INFO - [mozharness: 2017-06-09 00:22:22.204337Z] Running download-and-extract step. 17:22:22 INFO - Running pre-action listener: _resource_record_pre_action 17:22:22 INFO - Running main action method: download_and_extract 17:22:22 INFO - mkdir: /builds/slave/test/build/tests 17:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:22:22 INFO - trying https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:22:22 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/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 17:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/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 17:22:22 INFO - Downloaded 1328 bytes. 17:22:22 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:22:22 INFO - Using the following test package requirements: 17:22:22 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:22:22 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:22:22 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:22:22 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:22:22 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'jsshell-mac.zip'], 17:22:22 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:22:22 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:22:22 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:22:22 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:22:22 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 17:22:22 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:22:22 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:22:22 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:22:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:22:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:22:22 INFO - Fetch https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:22:25 INFO - Content-Length response header: 59543088 17:22:25 INFO - Bytes received: 59543088 17:22:43 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 17:22:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:22:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:22:43 INFO - Fetch https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:22:45 INFO - Content-Length response header: 59543088 17:22:45 INFO - Bytes received: 59543088 17:23:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:23:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:23:02 INFO - Fetch https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:23:03 INFO - Content-Length response header: 14447568 17:23:03 INFO - Bytes received: 14447568 17:23:03 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')]} 17:23:03 INFO - trying https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:23:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/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 17:23:05 INFO - Downloaded 68125282 bytes. 17:23:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:23:05 INFO - mkdir: /builds/slave/test/properties 17:23:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:23:05 INFO - Writing to file /builds/slave/test/properties/build_url 17:23:05 INFO - Contents: 17:23:05 INFO - build_url:https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:23:05 INFO - Running post-action listener: _resource_record_post_action 17:23:05 INFO - Running post-action listener: set_extra_try_arguments 17:23:05 INFO - [mozharness: 2017-06-09 00:23:05.771921Z] Finished download-and-extract step (success) 17:23:05 INFO - [mozharness: 2017-06-09 00:23:05.772038Z] Running populate-webroot step. 17:23:05 INFO - Running pre-action listener: _resource_record_pre_action 17:23:05 INFO - Running main action method: populate_webroot 17:23:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:23:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:23:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:23:05 INFO - '--tppagecycles', 17:23:05 INFO - '7'], 17:23:05 INFO - 'tests': ['tsvgm']}}, 17:23:05 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:23:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:23:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:23:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['damp', 'tps']}, 17:23:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'tests': ['damp', 'tps']}, 17:23:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['dromaeo_dom']}, 17:23:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:23:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:23:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:23:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:23:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['a11yr', 17:23:05 INFO - 'ts_paint', 17:23:05 INFO - 'tpaint', 17:23:05 INFO - 'sessionrestore', 17:23:05 INFO - 'sessionrestore_no_auto_restore', 17:23:05 INFO - 'tabpaint']}, 17:23:05 INFO - 'other-e10s': {'tests': ['a11yr', 17:23:05 INFO - 'ts_paint', 17:23:05 INFO - 'tpaint', 17:23:05 INFO - 'sessionrestore', 17:23:05 INFO - 'sessionrestore_no_auto_restore', 17:23:05 INFO - 'tabpaint']}, 17:23:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:23:05 INFO - 'ts_paint', 17:23:05 INFO - 'tpaint', 17:23:05 INFO - 'sessionrestore', 17:23:05 INFO - 'sessionrestore_no_auto_restore', 17:23:05 INFO - 'tabpaint']}, 17:23:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:23:05 INFO - 'ts_paint', 17:23:05 INFO - 'tpaint', 17:23:05 INFO - 'sessionrestore', 17:23:05 INFO - 'sessionrestore_no_auto_restore', 17:23:05 INFO - 'tabpaint']}, 17:23:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['a11yr', 17:23:05 INFO - 'ts_paint', 17:23:05 INFO - 'tpaint', 17:23:05 INFO - 'sessionrestore', 17:23:05 INFO - 'sessionrestore_no_auto_restore', 17:23:05 INFO - 'tabpaint']}, 17:23:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['a11yr', 17:23:05 INFO - 'ts_paint', 17:23:05 INFO - 'tpaint', 17:23:05 INFO - 'sessionrestore', 17:23:05 INFO - 'sessionrestore_no_auto_restore', 17:23:05 INFO - 'tabpaint']}, 17:23:05 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:23:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:23:05 INFO - 'bloom_basic_ref']}, 17:23:05 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 17:23:05 INFO - 'talos_options': ['--mitmproxy', 17:23:05 INFO - 'mitmproxy-recording-1.mp', 17:23:05 INFO - '--firstNonBlankPaint'], 17:23:05 INFO - 'tests': ['Quantum_1']}, 17:23:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['tsvgx', 17:23:05 INFO - 'tsvgr_opacity', 17:23:05 INFO - 'tart', 17:23:05 INFO - 'tscrollx', 17:23:05 INFO - 'cart', 17:23:05 INFO - 'tsvg_static']}, 17:23:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:23:05 INFO - 'tsvgr_opacity', 17:23:05 INFO - 'tart', 17:23:05 INFO - 'tscrollx', 17:23:05 INFO - 'cart', 17:23:05 INFO - 'tsvg_static']}, 17:23:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'talos_options': ['--disable-e10s'], 17:23:05 INFO - 'tests': ['tp5o']}, 17:23:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:23:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'talos_options': ['--disable-e10s', 17:23:05 INFO - '--xperf_path', 17:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:05 INFO - 'tests': ['tp5n']}, 17:23:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:23:05 INFO - 'talos_options': ['--xperf_path', 17:23:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:05 INFO - 'tests': ['tp5n']}}, 17:23:05 INFO - 'talos.zip': {'path': '', 17:23:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:23:05 INFO - Running post-action listener: _resource_record_post_action 17:23:05 INFO - [mozharness: 2017-06-09 00:23:05.793702Z] Finished populate-webroot step (success) 17:23:05 INFO - [mozharness: 2017-06-09 00:23:05.793837Z] Running create-virtualenv step. 17:23:05 INFO - Running pre-action listener: _resource_record_pre_action 17:23:05 INFO - Running main action method: create_virtualenv 17:23:05 INFO - Creating virtualenv /builds/slave/test/build/venv 17:23:05 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 17:23:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:23:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:23:05 INFO - Using real prefix '/tools/python27' 17:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:23:06 INFO - Installing distribute.............................................................................................................................................................................................done. 17:23:09 INFO - Installing pip.................done. 17:23:09 INFO - Return code: 0 17:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:23:09 INFO - Reading from file tmpfile_stdout 17:23:09 INFO - Output received: 17:23:09 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) 17:23:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:23:09 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')]} 17:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:09 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')]} 17:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:09 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 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:09 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 17:23:09 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 17:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:09 INFO - 'HOME': '/Users/cltbld', 17:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:09 INFO - 'LOGNAME': 'cltbld', 17:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:09 INFO - 'MOZ_NO_REMOTE': '1', 17:23:09 INFO - 'NO_EM_RESTART': '1', 17:23:09 INFO - 'PAGER': '/bin/cat', 17:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:09 INFO - 'PWD': '/builds/slave/test', 17:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:09 INFO - 'SHELL': '/bin/bash', 17:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:09 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:09 INFO - 'USER': 'cltbld', 17:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:09 INFO - 'XPC_FLAGS': '0x0', 17:23:09 INFO - 'XPC_SERVICE_NAME': '0', 17:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:10 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 17:23:10 INFO - Cleaning up... 17:23:10 INFO - Return code: 0 17:23:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:23:10 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')]} 17:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:10 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')]} 17:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:10 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 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:10 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 17:23:10 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 17:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:10 INFO - 'HOME': '/Users/cltbld', 17:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:10 INFO - 'LOGNAME': 'cltbld', 17:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:10 INFO - 'MOZ_NO_REMOTE': '1', 17:23:10 INFO - 'NO_EM_RESTART': '1', 17:23:10 INFO - 'PAGER': '/bin/cat', 17:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:10 INFO - 'PWD': '/builds/slave/test', 17:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:10 INFO - 'SHELL': '/bin/bash', 17:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:10 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:10 INFO - 'USER': 'cltbld', 17:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:10 INFO - 'XPC_FLAGS': '0x0', 17:23:10 INFO - 'XPC_SERVICE_NAME': '0', 17:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:10 INFO - Downloading/unpacking psutil>=3.1.1 17:23:10 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 17:23:10 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 17:23:10 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 17:23:10 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 17:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:23:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:12 INFO - Installing collected packages: psutil 17:23:12 INFO - Running setup.py install for psutil 17:23:12 INFO - building 'psutil._psutil_osx' extension 17:23:12 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 17:23:13 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 17:23:13 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 17:23:13 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 17:23:13 INFO - building 'psutil._psutil_posix' extension 17:23:13 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 17:23:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:23:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:23:13 INFO - ^ 17:23:13 INFO - 1 warning generated. 17:23:13 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 17:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:13 INFO - Successfully installed psutil 17:23:13 INFO - Cleaning up... 17:23:13 INFO - Return code: 0 17:23:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:23:13 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')]} 17:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:13 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')]} 17:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:13 INFO - 'HOME': '/Users/cltbld', 17:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:13 INFO - 'LOGNAME': 'cltbld', 17:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:13 INFO - 'MOZ_NO_REMOTE': '1', 17:23:13 INFO - 'NO_EM_RESTART': '1', 17:23:13 INFO - 'PAGER': '/bin/cat', 17:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:13 INFO - 'PWD': '/builds/slave/test', 17:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:13 INFO - 'SHELL': '/bin/bash', 17:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:13 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:13 INFO - 'USER': 'cltbld', 17:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:13 INFO - 'XPC_FLAGS': '0x0', 17:23:13 INFO - 'XPC_SERVICE_NAME': '0', 17:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:23:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:23:15 INFO - Installing collected packages: mozsystemmonitor 17:23:15 INFO - Running setup.py install for mozsystemmonitor 17:23:15 INFO - Successfully installed mozsystemmonitor 17:23:15 INFO - Cleaning up... 17:23:15 INFO - Return code: 0 17:23:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:23:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:23:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:15 INFO - 'HOME': '/Users/cltbld', 17:23:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:15 INFO - 'LOGNAME': 'cltbld', 17:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:15 INFO - 'MOZ_NO_REMOTE': '1', 17:23:15 INFO - 'NO_EM_RESTART': '1', 17:23:15 INFO - 'PAGER': '/bin/cat', 17:23:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:15 INFO - 'PWD': '/builds/slave/test', 17:23:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:15 INFO - 'SHELL': '/bin/bash', 17:23:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:15 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:15 INFO - 'USER': 'cltbld', 17:23:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:15 INFO - 'XPC_FLAGS': '0x0', 17:23:15 INFO - 'XPC_SERVICE_NAME': '0', 17:23:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:15 INFO - Downloading/unpacking jsonschema==2.5.1 17:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:23:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:17 INFO - Downloading functools32-3.2.3-2.tar.gz 17:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:23:17 INFO - warning: no files found matching '*.txt' 17:23:17 INFO - no previously-included directories found matching 'build' 17:23:17 INFO - no previously-included directories found matching 'dist' 17:23:17 INFO - no previously-included directories found matching '.git*' 17:23:17 INFO - Installing collected packages: jsonschema, functools32 17:23:17 INFO - Running setup.py install for functools32 17:23:17 INFO - warning: no files found matching '*.txt' 17:23:17 INFO - no previously-included directories found matching 'build' 17:23:17 INFO - no previously-included directories found matching 'dist' 17:23:17 INFO - no previously-included directories found matching '.git*' 17:23:17 INFO - Successfully installed jsonschema functools32 17:23:17 INFO - Cleaning up... 17:23:17 INFO - Return code: 0 17:23:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23: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')]} 17:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:17 INFO - 'HOME': '/Users/cltbld', 17:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:17 INFO - 'LOGNAME': 'cltbld', 17:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:17 INFO - 'MOZ_NO_REMOTE': '1', 17:23:17 INFO - 'NO_EM_RESTART': '1', 17:23:17 INFO - 'PAGER': '/bin/cat', 17:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:17 INFO - 'PWD': '/builds/slave/test', 17:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:17 INFO - 'SHELL': '/bin/bash', 17:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:17 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:17 INFO - 'USER': 'cltbld', 17:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:17 INFO - 'XPC_FLAGS': '0x0', 17:23:17 INFO - 'XPC_SERVICE_NAME': '0', 17:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:23:18 INFO - Cleaning up... 17:23:18 INFO - Return code: 0 17:23:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:23:18 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')]} 17:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:18 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')]} 17:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:18 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 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:18 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 17:23:18 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 17:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:18 INFO - 'HOME': '/Users/cltbld', 17:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:18 INFO - 'LOGNAME': 'cltbld', 17:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:18 INFO - 'MOZ_NO_REMOTE': '1', 17:23:18 INFO - 'NO_EM_RESTART': '1', 17:23:18 INFO - 'PAGER': '/bin/cat', 17:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:18 INFO - 'PWD': '/builds/slave/test', 17:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:18 INFO - 'SHELL': '/bin/bash', 17:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:18 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:18 INFO - 'USER': 'cltbld', 17:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:18 INFO - 'XPC_FLAGS': '0x0', 17:23:18 INFO - 'XPC_SERVICE_NAME': '0', 17:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:18 INFO - Downloading/unpacking blobuploader==1.2.4 17:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:19 INFO - Downloading blobuploader-1.2.4.tar.gz 17:23:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:23:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:23:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:20 INFO - Downloading docopt-0.6.1.tar.gz 17:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:23:20 INFO - Installing collected packages: blobuploader, requests, docopt 17:23:20 INFO - Running setup.py install for blobuploader 17:23:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:23:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:23:20 INFO - Running setup.py install for requests 17:23:21 INFO - Running setup.py install for docopt 17:23:21 INFO - Successfully installed blobuploader requests docopt 17:23:21 INFO - Cleaning up... 17:23:21 INFO - Return code: 0 17:23:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:21 INFO - 'HOME': '/Users/cltbld', 17:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:21 INFO - 'LOGNAME': 'cltbld', 17:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:21 INFO - 'MOZ_NO_REMOTE': '1', 17:23:21 INFO - 'NO_EM_RESTART': '1', 17:23:21 INFO - 'PAGER': '/bin/cat', 17:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:21 INFO - 'PWD': '/builds/slave/test', 17:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:21 INFO - 'SHELL': '/bin/bash', 17:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:21 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:21 INFO - 'USER': 'cltbld', 17:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:21 INFO - 'XPC_FLAGS': '0x0', 17:23:21 INFO - 'XPC_SERVICE_NAME': '0', 17:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:23:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-dCygHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:23:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-xzR6Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-bp2D6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1rpTUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-D65Aix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Dgqe0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-g7mOTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-jlTD8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1EK_BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-oJDqrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:23:22 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8VyS3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:23:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-pJe4Ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:23:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MC3pOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:23:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Rssx5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-98RBkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:23:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-iTejfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:23:23 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VY3JPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:23:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:23:23 INFO - Running setup.py install for manifestparser 17:23:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:23:23 INFO - Running setup.py install for mozcrash 17:23:23 INFO - Running setup.py install for mozdebug 17:23:24 INFO - Running setup.py install for mozdevice 17:23:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Running setup.py install for mozfile 17:23:24 INFO - Running setup.py install for mozhttpd 17:23:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Running setup.py install for mozinfo 17:23:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Running setup.py install for mozInstall 17:23:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:23:24 INFO - Running setup.py install for mozleak 17:23:24 INFO - Running setup.py install for mozlog 17:23:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:23:25 INFO - Running setup.py install for moznetwork 17:23:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:23:25 INFO - Running setup.py install for mozprocess 17:23:25 INFO - Running setup.py install for mozprofile 17:23:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:23:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:23:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:23:25 INFO - Running setup.py install for mozrunner 17:23:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:23:25 INFO - Running setup.py install for mozscreenshot 17:23:25 INFO - Running setup.py install for moztest 17:23:26 INFO - Running setup.py install for mozversion 17:23:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:23:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:23:26 INFO - Cleaning up... 17:23:26 INFO - Return code: 0 17:23:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:26 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')]} 17:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:26 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')]} 17:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:26 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 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:26 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 17:23:26 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 17:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:26 INFO - 'HOME': '/Users/cltbld', 17:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:26 INFO - 'LOGNAME': 'cltbld', 17:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:26 INFO - 'MOZ_NO_REMOTE': '1', 17:23:26 INFO - 'NO_EM_RESTART': '1', 17:23:26 INFO - 'PAGER': '/bin/cat', 17:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:26 INFO - 'PWD': '/builds/slave/test', 17:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:26 INFO - 'SHELL': '/bin/bash', 17:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:26 INFO - 'USER': 'cltbld', 17:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:26 INFO - 'XPC_FLAGS': '0x0', 17:23:26 INFO - 'XPC_SERVICE_NAME': '0', 17:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:23:26 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-iv8dEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:23:26 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_6vmuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:23:26 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-p4mJny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:23:26 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VcRDjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:23:26 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)) 17:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:23:26 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0JgyFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:23:27 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)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RC4Zsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:23:27 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)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_lK7PA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-cmg39H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-BMoitM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-24xGAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0ooiTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-H0x4WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_be1Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:23:27 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-2nKQ3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:23:28 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)) 17:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:28 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-NiUr0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:28 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)) 17:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:23:28 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_QkVAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:23:28 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)) 17:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:23:28 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-he3yON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:23:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:29 INFO - Downloading blessings-1.6.tar.gz 17:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:23:29 INFO - Installing collected packages: blessings 17:23:29 INFO - Running setup.py install for blessings 17:23:30 INFO - Successfully installed blessings 17:23:30 INFO - Cleaning up... 17:23:30 INFO - Return code: 0 17:23:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:23:30 INFO - Reading from file tmpfile_stdout 17:23:30 INFO - Current package versions: 17:23:30 INFO - blessings == 1.6 17:23:30 INFO - blobuploader == 1.2.4 17:23:30 INFO - docopt == 0.6.1 17:23:30 INFO - functools32 == 3.2.3-2 17:23:30 INFO - jsonschema == 2.5.1 17:23:30 INFO - manifestparser == 1.1 17:23:30 INFO - mozInstall == 1.12 17:23:30 INFO - mozcrash == 1.0 17:23:30 INFO - mozdebug == 0.1 17:23:30 INFO - mozdevice == 0.51 17:23:30 INFO - mozfile == 1.2 17:23:30 INFO - mozhttpd == 0.7 17:23:30 INFO - mozinfo == 0.10 17:23:30 INFO - mozleak == 0.1 17:23:30 INFO - mozlog == 3.4 17:23:30 INFO - moznetwork == 0.27 17:23:30 INFO - mozprocess == 0.25 17:23:30 INFO - mozprofile == 0.28 17:23:30 INFO - mozrunner == 6.13 17:23:30 INFO - mozscreenshot == 0.1 17:23:30 INFO - mozsystemmonitor == 0.3 17:23:30 INFO - moztest == 0.8 17:23:30 INFO - mozversion == 1.4 17:23:30 INFO - psutil == 3.1.1 17:23:30 INFO - requests == 1.2.3 17:23:30 INFO - wsgiref == 0.1.2 17:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:30 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 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23:30 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 17:23:30 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 17:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:30 INFO - 'HOME': '/Users/cltbld', 17:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:30 INFO - 'LOGNAME': 'cltbld', 17:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:30 INFO - 'MOZ_NO_REMOTE': '1', 17:23:30 INFO - 'NO_EM_RESTART': '1', 17:23:30 INFO - 'PAGER': '/bin/cat', 17:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:30 INFO - 'PWD': '/builds/slave/test', 17:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:30 INFO - 'SHELL': '/bin/bash', 17:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:30 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:30 INFO - 'USER': 'cltbld', 17:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:30 INFO - 'XPC_FLAGS': '0x0', 17:23:30 INFO - 'XPC_SERVICE_NAME': '0', 17:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:23:30 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:23:30 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 17:23:30 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 17:23:30 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 17:23:30 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 17:23:32 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:23:32 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)) 17:23:32 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)) 17:23:32 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)) 17:23:32 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)) 17:23:32 INFO - Installing collected packages: marionette-driver 17:23:32 INFO - Running setup.py install for marionette-driver 17:23:32 INFO - Successfully installed marionette-driver 17:23:32 INFO - Cleaning up... 17:23:32 INFO - Return code: 0 17:23:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23: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')]} 17:23:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecec768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c894cb830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, '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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 17:23: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', 'jsonschema'] in /builds/slave/test/build 17:23: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 jsonschema 17:23:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:32 INFO - 'HOME': '/Users/cltbld', 17:23:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:32 INFO - 'LOGNAME': 'cltbld', 17:23:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:32 INFO - 'MOZ_NO_REMOTE': '1', 17:23:32 INFO - 'NO_EM_RESTART': '1', 17:23:32 INFO - 'PAGER': '/bin/cat', 17:23:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:32 INFO - 'PWD': '/builds/slave/test', 17:23:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:32 INFO - 'SHELL': '/bin/bash', 17:23:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:32 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:32 INFO - 'USER': 'cltbld', 17:23:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:32 INFO - 'XPC_FLAGS': '0x0', 17:23:32 INFO - 'XPC_SERVICE_NAME': '0', 17:23:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:23:32 INFO - Cleaning up... 17:23:32 INFO - Return code: 0 17:23:32 INFO - Running post-action listener: _resource_record_post_action 17:23:32 INFO - Running post-action listener: _start_resource_monitoring 17:23:32 INFO - Starting resource monitoring. 17:23:32 INFO - [mozharness: 2017-06-09 00:23:32.750904Z] Finished create-virtualenv step (success) 17:23:32 INFO - [mozharness: 2017-06-09 00:23:32.751925Z] Running install step. 17:23:32 INFO - Running pre-action listener: _resource_record_pre_action 17:23:32 INFO - Running main action method: install 17:23:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:23:33 INFO - Reading from file tmpfile_stdout 17:23:33 INFO - Detecting whether we're running mozinstall >=1.0... 17:23:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:23:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:23:33 INFO - Reading from file tmpfile_stdout 17:23:33 INFO - Output received: 17:23:33 INFO - Usage: mozinstall [options] installer 17:23:33 INFO - Options: 17:23:33 INFO - -h, --help show this help message and exit 17:23:33 INFO - -d DEST, --destination=DEST 17:23:33 INFO - Directory to install application into. [default: 17:23:33 INFO - "/builds/slave/test"] 17:23:33 INFO - --app=APP Application being installed. [default: firefox] 17:23:33 INFO - mkdir: /builds/slave/test/build/application 17:23:33 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'] 17:23:33 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 17:23:54 INFO - Reading from file tmpfile_stdout 17:23:54 INFO - Output received: 17:23:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:23:54 INFO - Running post-action listener: _resource_record_post_action 17:23:54 INFO - [mozharness: 2017-06-09 00:23:54.857950Z] Finished install step (success) 17:23:54 INFO - [mozharness: 2017-06-09 00:23:54.858094Z] Running setup-mitmproxy step. 17:23:54 INFO - Running pre-action listener: _resource_record_pre_action 17:23:54 INFO - Running main action method: setup_mitmproxy 17:23:54 INFO - Skipping: mitmproxy is not required 17:23:54 INFO - Running post-action listener: _resource_record_post_action 17:23:54 INFO - [mozharness: 2017-06-09 00:23:54.858621Z] Finished setup-mitmproxy step (success) 17:23:54 INFO - [mozharness: 2017-06-09 00:23:54.858731Z] Running run-tests step. 17:23:54 INFO - Running pre-action listener: _resource_record_pre_action 17:23:54 INFO - Running main action method: run_tests 17:23:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:23:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:23:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:23:54 INFO - 2017-06-08 17:23:54.873 screenresolution[2479:36363] starting screenresolution argv=screenresolution get 17:23:54 INFO - 2017-06-08 17:23:54.875 screenresolution[2479:36363] Display 0: 1600x1200x32@60 17:23:54 INFO - 2017-06-08 17:23:54.881 screenresolution[2480:36365] starting screenresolution argv=screenresolution list 17:23:54 INFO - Available Modes on Display 0 17:23:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:23:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:23:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:23:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:23:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:23:55 INFO - Intel Iris: 17:23:55 INFO - Chipset Model: Intel Iris 17:23:55 INFO - Type: GPU 17:23:55 INFO - Bus: Built-In 17:23:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:23:55 INFO - Vendor: Intel (0x8086) 17:23:55 INFO - Device ID: 0x0a2e 17:23:55 INFO - Revision ID: 0x0009 17:23:55 INFO - Displays: 17:23:55 INFO - Display: 17:23:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:23:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:23:55 INFO - Display Serial Number: 1600x1200 60 17:23:55 INFO - Main Display: Yes 17:23:55 INFO - Mirror: Off 17:23:55 INFO - Online: Yes 17:23:55 INFO - Rotation: Supported 17:23:55 INFO - Return code: 0 17:23:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:23:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:23:55 INFO - Python 2.7.3 17:23:55 INFO - Return code: 0 17:23:55 INFO - grabbing minidump binary from tooltool 17:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:23:55 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 0x10ea3ed30>, '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 0x10ea08cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea2fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:23:55 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 17:23:55 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 17:23:55 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 17:23:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:23:55 INFO - Return code: 0 17:23:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:23:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:23:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:23:55 INFO - ENV: RUST_BACKTRACE is now 1 17:23:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:23:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:23:55 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/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 17:23:55 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/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 17:23:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ppYL4LTgah/Render', 17:23:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:55 INFO - 'HOME': '/Users/cltbld', 17:23:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:55 INFO - 'LOGNAME': 'cltbld', 17:23:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:23:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:55 INFO - 'MOZ_NO_REMOTE': '1', 17:23:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:23:55 INFO - 'NO_EM_RESTART': '1', 17:23:55 INFO - 'PAGER': '/bin/cat', 17:23:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:55 INFO - 'PWD': '/builds/slave/test', 17:23:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:23:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:55 INFO - 'RUST_BACKTRACE': '1', 17:23:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:23:55 INFO - 'SHELL': '/bin/bash', 17:23:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9Oiqm5ytsi/Listeners', 17:23:55 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 17:23:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:55 INFO - 'USER': 'cltbld', 17:23:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:55 INFO - 'XPC_FLAGS': '0x0', 17:23:55 INFO - 'XPC_SERVICE_NAME': '0', 17:23:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:55 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/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 17:23:55 INFO - mozversion application_buildid: 20170608161222 17:23:55 INFO - mozversion application_changeset: 5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705 17:23:55 INFO - mozversion application_display_name: Nightly 17:23:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:23:55 INFO - mozversion application_name: Firefox 17:23:55 INFO - mozversion application_remotingname: firefox 17:23:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:23:55 INFO - mozversion application_vendor: Mozilla 17:23:55 INFO - mozversion application_version: 55.0a1 17:23:55 INFO - mozversion platform_buildid: 20170608161222 17:23:55 INFO - mozversion platform_changeset: 5c61d5d5fc653454cb816b2bcf8c6a9f5a71c705 17:23:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:23:55 INFO - mozversion platform_version: 55.0a1 17:23:55 INFO - using testdate: 1496967835 17:23:55 INFO - actual date: 1496967835 17:23:55 INFO - starting webserver on 'localhost:58130' 17:23:55 INFO - SUITE-START | Running 2 tests 17:23:55 INFO - TEST-START | dromaeo_css 17:23:55 INFO - operating with platform_type : mac_ 17:23:55 INFO - Initialising browser for dromaeo_css test... 17:23:55 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58130/getInfo.html -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpvpJlJI/profile 17:23:55 INFO - TEST-INFO | started process 2490 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58130/getInfo.html -foreground) 17:24:29 INFO - TEST-INFO | 2490: killed by SIGTERM 17:24:29 INFO - Browser initialized. 17:24:29 INFO - Running cycle 1/1 for dromaeo_css test... 17:24:29 INFO - TEST-INFO | started process 2498 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpvpJlJI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:30 INFO - PID 2498 | 2017-06-08 17:24:30.982 plugin-container[2499:37020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 17:24:30 INFO - PID 2498 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:30 INFO - PID 2498 | 2017-06-08 17:24:30.982 plugin-container[2499:37020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 17:24:30 INFO - PID 2498 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:30 INFO - PID 2498 | 2017-06-08 17:24:30.982 plugin-container[2499:37020] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:24:31 INFO - PID 2498 | 2017-06-08 17:24:31.747 plugin-container[2500:37068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 17:24:31 INFO - PID 2498 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:31 INFO - PID 2498 | 2017-06-08 17:24:31.748 plugin-container[2500:37068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490f, name = 'com.apple.CFPasteboardClient' 17:24:31 INFO - PID 2498 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:24:31 INFO - PID 2498 | 2017-06-08 17:24:31.748 plugin-container[2500:37068] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:24:41 INFO - PID 2498 | RSS: Main: 253607936 17:24:41 INFO - PID 2498 | 17:27:43 INFO - PID 2498 | Cycle 1(1): loaded http://localhost:58130/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58130/tests/dromaeo/cssquery-ext.html) 17:27:44 INFO - PID 2498 | RSS: Main: 264044544 17:27:44 INFO - PID 2498 | 17:30:46 INFO - PID 2498 | Cycle 1(1): loaded http://localhost:58130/tests/dromaeo/cssquery-ext.html (next: http://localhost:58130/tests/dromaeo/cssquery-jquery.html) 17:30:47 INFO - PID 2498 | RSS: Main: 275730432 17:30:47 INFO - PID 2498 | 17:33:49 INFO - PID 2498 | Cycle 1(1): loaded http://localhost:58130/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58130/tests/dromaeo/cssquery-mootools.html) 17:33:49 INFO - PID 2498 | RSS: Main: 275791872 17:33:49 INFO - PID 2498 | 17:36:51 INFO - PID 2498 | Cycle 1(1): loaded http://localhost:58130/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58130/tests/dromaeo/cssquery-prototype.html) 17:36:52 INFO - PID 2498 | RSS: Main: 278573056 17:36:52 INFO - PID 2498 | 17:39:55 INFO - PID 2498 | Cycle 1(1): loaded http://localhost:58130/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58130/tests/dromaeo/cssquery-yui.html) 17:39:55 INFO - PID 2498 | RSS: Main: 277622784 17:39:55 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | Cycle 1(1): loaded http://localhost:58130/tests/dromaeo/cssquery-yui.html (next: http://localhost:58130/tests/dromaeo/cssquery-dojo.html) 17:42:57 INFO - PID 2498 | RSS: Main: 276623360 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | __start_tp_report 17:42:57 INFO - PID 2498 | _x_x_mozilla_page_load 17:42:57 INFO - PID 2498 | _x_x_mozilla_page_load_details 17:42:57 INFO - PID 2498 | |i|pagename|runs| 17:42:57 INFO - PID 2498 | |0;dojo.html;2930.4578652949212;3179.2210908327456;3558.8754393596228;3594.06554295883;3595.85616575337;6092.573519853612;6178.5983911045805;6213.689315534217;6213.813585592428;6220.595661282019;6096.786612468553;6114.87770244596;6125.9387406125925;6139.768135342631;6171.598846075013;5295.179247216668;5366.9463305367035;5377.193420986844;5385.596080293974;5390.434004429518;5394.109971854639;5469.972650136764;5482.17767334901;5484.7531861066345;5490.121580547113;5300.337457817773;5347.438518955492;5377;5448.400675925645;5456.072467680484;5386.811461598864;5398.676079435247;5420.214068960018;5420.91868621971;5459.2902922619805;5353.759080841332;5366.302380690524;5382.354117505885;5397;5397.973010134924;6031.45716885478;6246.656433896134;6255.343188965138;6264.9373506265265;6266.122742815964;6393.968030159819;6505.902411463831;6593.076969224312;6600;6606.86786264272;6707.3628005339415;6711.094002309675;6722.1261236039;6739.898901516482;6744.5616034957575;6266.905996410058;6292.622442653455;6316.084167795644;6317.747290108436;6338.080978258219;6589.275179730226;6681.9331806682285;6737.663116844138;6812.386885180357;6840.965795170992;6181.876362472725;6319.810405687745;6522.934770652328;6546.116274302908;6553.868922621521;6581.440577550866;6679.532432729662;6690.799276021728;6691.297413771581;6699.330066993359;6550.672466376757;6551.344865513506;6554.573952693059;6581.111549940792;6583.440407565315;6317.368263173646;6396;6517.739290428424;6521.413072823374;6583.868322633521;5314.7608357624;5339.199120132012;5340.706261155674;5358.142697168512;5369.7583608737705;4724.338592597039;4731.4558825735485;4745.762711864393;4749.145153872336;4782.904341913212;6119.746288422617;6274.372562743689;6291.622502649856;6309.936900630935;6312.053192021233;5291.021161085211;6724.092247546617;6763.695633696496;6765.560238584476;6780.660966951633;5109.932824366633;6284.151639528605;6311.842203944938;6318;6326.2092238470195;4808.663393562487;5749.770009199585;5819.970900145557;5901.409859014064;5924.822255332347;5874.706264686663;6806.965965170142;6844.1444819397575;6846.178458585002;6871.415929646036;5903.848749493807;6741.460683145435;6751.392374686302;6764.458843292623;6780.32196780318;6653.201615806134;6850.965745171442;6914.757983470555;6948.965255173692;6957.652117394211;6417.935820641734;6450.258220304725;6541.934580654323;6564.212294524687;6603.966980165068;5925.755591325697;6691.197056353269;6710.899336510054;6739.157605299338;6753.493487988519;6278.4035516625845;6309.3690630936535;6398.776042838478;6481.546291759531;6483.222013358427;6465.321141280286;6486.675666216576;6496.252930913006;6511.804645860631;6545.050967609765;6317.96841015792;6384.6169229846355;6385.010323399881;6395.136656551306;6421.550491465552;6316.905246421215;6507.642079685474;6510.511711621552;6543.280239173592;6559.901601475886;6156.384361563987;6200.782972595937;6211.9129152397245;6229.034499652561;6302.779402720883 17:42:57 INFO - PID 2498 | |1;ext.html;772.9072511298644;793.1711361627099;831.7629475599456;1095.7315457712857;1150.2293463379535;37527.62472375276;38922.221555568874;39028.82913512599;39041;39062.60937390625;38869.22261554774;39404.80297598515;39440.21119577614;39483.210335793265;39579.604203957955;7835.6473958671995;7892.40806939479;8177.672893084269;8449.155084491533;8460.36547258955;8445.873311900295;8502.489850608952;8557.48655080694;8587.785305367384;8601.61292741825;8513.361497887652;8554.657813687476;8576.271016963534;8610.225079742822;8614.224719775219;8540.017897941723;8555.614997325105;8582.613782379745;8614.396992210548;8633.956830215871;8307;8359.623816928191;8428.241458268785;8428.578571071423;8431.873521897176;36935.445968310494;37019.629803702155;37076.44385334222;37083;37243.255134897154;3625.0212442640604;3673.008287762316;3673.5775385830702;3675.5221821163077;3689.797061161635;29935.251618709488;30062.54906176409;30120.69879301201;30266.243343916358;30302.545461818092;3022.909312720622;3049.130997665655;3076.1848110250803;3118.3607360491046;3118.3919135768533;225364;228179;237435.81282093652;298083;298304;40503.59496405057;46847.531524685;46852.7657361711;46995.53004469912;47063;48544;48848.75575622099;49070;49146.508534914195;51049.979000419786;14449.711005780036;14533;14538.41846326135;14558.636034099232;14579.469155738707;11569.958703716538;11573.479193436315;11645.184837061324;11659.650210493699;11670.299782013106;14179.078359906573;14242.43030278798;14256.928715356356;14281.571552853431;14323.283835808168;6043.395660433922;6060.091156245027;6071.51427885768;6085.817425477243;6087.391260873966;895.6462197432108;978.9510524473748;996.7757254617653;1007.6120693532896;1021.8824835144054;21961.682299062108;23161.53676926452;24832.00671973101;24839;24910.5017899641;938.9108633984681;1098.81869491533;1099.197585762398;1163.959261425846;1173.6889724223088;9536.52317384128;11978.161528693083;12515.56195533152;12756.936215318863;12786.168899021533;274578;278204.60897695384;311798;320748;321185.3940730281;48725.26912096321;48941;49021;49087.75456122697;49305;1326.9384492406036;1480.637243875236;1524.9161296128818;1527.8243002054905;1530.9464168754264;20978.685319719913;22175.889120554293;22320.330390088322;22786.202482913017;24074.759252407253;36757.816210918776;36823.815880920425;36828.631713682524;36869.44695829618;37137.62862371342;263996;264553;265099;265183;267131;34405.13987150241;34613.82693086519;35307.823460883556;36048.4592731104;36049.459258111674;43683.68948931537;44729;44898;45072;45136;29244.853775730986;29572.964946226777;29605.85197074001;30098.849505752332;31042.84478577683;24346.75653243445;28515.572266415606;29849.25376865596;30614;31072.22319442032;36854.63145368512;37826;37856.62143378531;37961.240775184895;38026.429603555414;30114.24714382158;30695.539566906067;30746.385072298875;30749.385012299183;31689.890853820005;23169.07323707033;24751.486088216625;24759.38101547476;24909.501809963338;24945.50108997846 17:42:57 INFO - PID 2498 | |2;jquery.html;439.72517176764524;459.6024438860386;490.6467343512672;502.74815708884887;517.464424320828;21748.45628859279;22135.66796498052;22843.657345139836;22964.770352296473;24293.878530607366;24527.018919243208;25018.124365647207;25159.74840251597;25255.368615784613;25296.241112766602;6968.860622787542;7122.252163522833;7128.14462264529;7137.928620713804;7152.570845749246;6795;6886.827829304258;6966.79099627012;6971.3028697130385;7041.119860017498;6883.27725588811;6954.965225173867;6968.338007889242;6979.162500499921;6998.985147153659;6937.340952609494;6953.269906659779;6953.269906659803;6971.895421568655;6981.79054628362;6791.966040169817;6824.18109826819;6825.078614387044;6838.623875686835;6838.863222735567;30203.395932081454;30815.845920770476;30862;31111.533327000114;31574;2218.511927375991;2257.1535674122206;2264.8980795864227;2266.3880752196815;2270.3642979965634;15742.661873740746;20012.199512019502;20597.867117308542;20613;20633.896830515903;2074.5228597422524;2186.650135978252;2296.954060918789;2302;2354.540864531417;167733.48399774014;173999;194009;197595;206710.9999999985;22700.205492807672;24669.259922202364;24848.627270590954;26390.47219055608;26656.60015099775;44491.555084448744;44527.77736111299;44605.10789784186;44609.330860037124;44814.55185448169;8307.501549907025;10056;11095.72260693489;11141.387223702774;11700.765984680429;8711.34664900135;9003.864942025875;9047.77380565478;9056.456612603264;9100.271978241724;10342.172626189888;12227.510899563918;12452.377381130907;12457.439415226336;12514.014360671124;4819.807207711652;4905.730184839868;4917.803287868445;4925.7783399747095;4930.704157750546;951.8667386565747;963.5726156015941;965.1988849255101;1011.1960991012163;1013.5793645637126;16843.073630950414;20332.288369906983;20747.481312967295;20875.164993400092;21407.250746223806;966.8790428481215;972.3193764364972;975.2929126383457;1013.2096964367657;1015.6952914125881;9307.953460232655;10136.746581335525;10528.473576321154;10545.9472702636;10692.305000174963;532299;551634;552213;554438;557886;137768;137967;143628.5637143636;150634.7404788929;159207;1101.8787933327487;1194.26552670109;1228.342836582424;1235.721962558417;1254.2161149281699;22118;22604.321870343916;23427.414314642272;23718.52562948766;23774.811259437305;64727;66567.00149497665;67493.3250667487;67535.66232168808;68861.96707049297;89484.5525772367;89977.65033524371;90270;90273.54863225904;90523.54738226267;11386.202965792647;11630.418479076181;11657.650270491898;11666.88333116658;11678.88321116778;17713.468595942144;18334.266629334677;18401.815981840547;18587.44237672872;18665.6266874659;14801.481948131765;14963.925180374028;15140.091594504365;15227.695446091238;15276.465323713617;13342.266175360448;13561.525346612822;13584.32078396096;13636.727265454834;13732.725345493234;15189.468368607046;15250.31373588169;15562.221888905737;15600.609984750472;15660.843391566394;11583.362915039754;11608.419579020847;11667.6499705009;11693.356865372147;11694.649160525198;14957.850421495647;15125.773113403087;15223.847761522244;15274.15992120444;15295.541133766004 17:42:57 INFO - PID 2498 | |3;mootools.html;1407.866252705993;1451.7677171652538;1455.9226172632254;1460.583733635914;1470.4118352658936;12733.681657958545;12818.102732808708;13446.327683615817;13464.59606211814;13677.179369237852;13389.866101339008;13530.052896297238;13574.796378054338;13601.93199034006;13657.248851313174;2711.3628297350106;2744.890204391822;2749.257700420885;2763.9723602763916;2770.515159847029;2643;2689.2066840282064;2689.40833016737;2690.367763575568;2700.7839372850126;2652.5225459417297;2686.838789672626;2693.138195777352;2695.636089127972;2700.203439985209;2608.7391260873856;2643.8413695178256;2659.6941351744604;2662.1614191529607;2668.1862032080207;2221.766714494987;2296.242240060776;2617.5942728877053;2633.565461698817;2648.602709593557;12852;13258.337083145901;13267.933660331733;13270.469181232738;13291.003174761932;7331.706731730725;7595.81010474737;7647.044119485065;7656.157822639505;7668;4211.263028969948;4339;4423.270160423526;4423.469183697945;4433.268510695763;1262.1543565810928;1404.4101477379525;1431.0340520148857;1455.2069122328357;1457.460739526382;18590.349337773114;19654.90172549128;19658.213671453268;19685.40943771689;19716.112774925165;6321.55749097568;6383.425491705676;6423.454006409414;6529.053287273318;6539.215294164635;13798.65503362424;14046.71906561863;14049.438022479191;14057.85942140586;14120.50578229757;6855.771874140924;7150.892736608955;7263.963680181565;7288.526245794006;7315.085614298213;6086.330503644595;6461.19235095613;6667.366600172976;6727.394534491919;6728.293529179464;4781.545753153445;4979.278004689299;4980.551750342486;5036.471170527115;5042.470540593258;1429.5068201470476;1449.6375906023495;1451.397669966973;1468.8237411510688;1476.7489526780473;688.6246995387426;823.4318320358933;838.237204144238;858.2447446247387;893.5710858787818;7312.488125831141;7611.1627720950655;7704.26809453101;7779.961100194463;8024.398170137147;993.3493561717087;1005.3314545826943;1011.7066050845173;1012.8632634594383;1047.0628787235382;4608.493065762764;4734.573888350065;4769.950610865604;5129.204973229155;5299.602529810202;16598.336066557204;17394.82605173932;17396.565085872702;17450.214740336716;17857.910710446624;17145.971241725536;17298.394556190473;17544;17558.91220543915;17894.731579026324;9277.396969196981;9322.25421966241;9353.485558294495;9417.058294170529;9438.95280523593;6618.900716489353;7612.809679757829;7813.882791758242;8113;8346.833063338821;15543.300551474984;15761.290741916411;15995.440159594358;16037.278322475284;16060.67878642444;15813.841861581237;16125.75811362807;16171.110588917723;16354.264058117651;16730.665386692443;9596.18432433251;9745.269104817025;9745.610175593181;9759.853602195832;9785.36395134314;11505.849415058761;12066;12238;12304.26174429537;12325.383730813244;9010.279177665901;9346.579403926975;9389.859152112587;10084.344517606332;10114.241431892491;9275.72172834816;9283.907160928575;9792.461414622265;9877.160441362277;10046.24653151002;9053.59258833341;9092.909070909207;9144.405613635092;9209.76975575616;9260.722178334661;6623.3376662333385;6851.725930962705;6875.896861547181;6920.688569014507;7078.610676412847;6586.4401525870835;6657.334266573498;6831.9316806833285;6968.198657154492;7059 17:42:57 INFO - PID 2498 | |4;prototype.html;446.09888026187105;452.09129649404696;463.6684770389171;466.3424571354391;473.1719490890941;5896.877124235277;6233.40782625651;6351.301356850754;6426.389492998158;6433.774817881375;4815.975920120403;6126.295476020248;6198.078595635357;6270.686465676721;6284.277308109557;3950.980245098785;4012.699047571429;4704.882377940553;4760.309755085511;4767.1657459944545;4346.130773845228;4758.619310455155;4773.618110551148;4798.7840547175465;4838;4322.4164737760475;4701.224297990844;4702.717836929778;4710.62315014798;4754.358161648185;4231.492220933507;4734.810607575693;4750.501197374295;4758.167320718878;4776.522347765213;3704.2776658551593;4116.773577453238;4124.360724087741;4146.543880173179;4166.708330416872;5518.862028449281;5680.573956953203;5738.368779434259;6265.091561723524;6279.968600157015;4606.976965115153;5654.773809047674;5664.745086471077;5667.206591077253;5703.971480142573;6796.184457865038;6817.863642727118;6830.829229269259;6835.521513494057;6838.7264509419565;4766.30888521166;4766.928496072562;4772.475027746945;4777.737224452654;4786.401699787527;5861.267341582302;6647.700853461557;7261.782146535613;7296.233895440955;7299.781006569811;4268.637165840876;4443.622292105143;4552.157850797546;4747.359106520576;4834.516548345137;4221.493420789464;4931.50958765821;4944.703317800943;4951.900961980812;4973.50264973507;5530.723463826873;5591.804286849941;5603.299587551556;5613.933352662981;5628.409017053233;6836.179658440919;6845.8973115403305;6879.690413931385;6890.415204502992;6901.965490172617;1388.2017839742189;1653.9421120260733;1676.6227598790174;1681.7477378393094;1694.5001224638515;1111.2054880760295;1124.6288724720823;1150.2580835361312;1151.7330935970365;1161.8780028096928;6426.3573642635365;6456.903146452807;6468.544577470032;6473.482121430275;6506.121673574007;6250.656213908188;6775.796726098225;7020.403265722471;7056.48385033579;7095.751648692271;6267.1539342189135;6423.710933008026;6437.420632143129;6438.8390290243115;6447.4210315874025;3344.7993120412852;3441.0537102296566;3664.13892735207;3671.81640917953;3727.6702061211745;6057.153707395835;6359.554831161867;6994.790156295319;7268.927310726931;7272.63636818157;5902.734376953047;7261.2012678605315;7264.527805692719;7293.343599076109;7306.415486761046;7184.317489838457;7249.963750181215;7268.164161121539;7296.306850849161;7343.228960959129;6003.189569408249;6949;7041.084658994298;7102.076730025064;7239.746608868875;6017.578769486094;6691.862383394932;6704.262531121475;6709.127813384229;6906.412954898889;6060.333363329942;7183.138023437221;7250.746223882139;7304.68515667185;7329.120505539369;4801.423829140525;4820.276958456259;4832.226843705131;4849.854504364875;4855.223164293696;6917.757878474431;7044.189918159477;7068.222495525385;7074.929250707427;7085.114360704912;5467.39858615544;5758.740856661377;6239.594426362453;6357.046443033397;6794.72821087151;5936.28764548257;5992.861356342455;6769.221539523018;6793.150856142982;6889.862202756017;5589.608727389043;6613.702383392855;6633.502487313374;6662.866742665216;6707.66461676924;5408.161734931092;5895.7052147393315;6100.176476175837;6474.514411419256;6763.898541521783;5826.41735826414;6623.178625877922;6637.734490620514;6641.634710090992;6644.60132392058 17:42:57 INFO - PID 2498 | |5;yui.html;829.7303376402671;840.701550949413;842.7556008757457;864.4294765454802;871.3290766110093;14131.364088616012;14752.114873107621;16383.017018978855;16697.83302166978;17020.234089465972;15994.600134996603;17874.731908809295;17980.730289045674;18012.09939503026;18181.09094545274;989.4706332112304;996.8205722969863;1015.1117771949545;1015.8476228565737;1018.1040684197895;1009.2934945538116;1013.7313611892854;1015.5937624950042;1015.8374660054393;1016.2682868334787;948.1371951524114;1049.490996866519;1059.390850261099;1059.7403636109163;1070.9518071686755;1048.09863517375;1052.0951981296118;1053.5838343854211;1054.6361505280704;1055.6516349604613;761.2197497564985;762.0246085011195;762.2872614105829;762.6758627583255;771.7877583664481;16124.75812862808;16519.33922643105;16553.172341382884;16554.17229138538;16978.915105424516;16318.75521867173;17027.829721702874;17301.82698173027;17570.736438953427;17915.283388664437;13690.315484225848;13715.245661488614;13833.723325533534;14901.70196596062;15042.924785376112;3370.7303415726683;3592.4790905318846;3618.782873027627;3622.601513833476;3628.76413033152;152635.2368238163;154182.22908885495;154330;155784;156567.21716391458;10838.512266948006;13497.797533037012;13523.729525409437;13618.659533511543;13823.79264311036;22704.65943010856;25905;26095.73904260971;26365;26534.33664158373;6613.735450582019;6864.107666003488;7557;7826.452148349561;8076.4750291230885;3909.276783795007;4001.979990100031;4004.579519150447;4024.215278020758;4027.8791636250958;4522.706024108423;4605.217113090783;4608.354830323758;4632.050429661911;4643.419572553431;1009.5204777730667;1019.2864994503877;1021.0759262867117;1023.5854478936043;1034.4569101221919;640.6322501459417;642.7814543055383;652.2205963873221;660.6696651674163;668.6957434367351;11228.382438965771;11385.487653055632;11939.88060119405;13049.739005220032;13309.534166304327;657.2769953051605;659.261626977788;669.3774789445765;677.2888467109515;684.3772167327808;5743.885122297531;5843.5909486336395;5862.149988251765;5887.499562537147;6022.909656355158;130290;132547.67452325547;132812.33593831968;134579;137939;113403;115089.27366089515;116673;118882.40558797323;121078.3946080264;98406;99756.50121749345;102706;104078;104433;10730.302530335503;11271.887281127083;11576.710582235512;11772.881576250242;11805.76388472243;43052;44909.10181796411;45181.32228016648;46332.53667463282;46615.766921166534;46372;55562.444375557345;58334.54163645773;61875;61905.38094618996;14516.346764395415;14749.926250368679;14760.926195368955;14913.25433728331;15004.174770387297;15740.527784166494;16091.597710057342;16095.67808643844;16097.678046438772;16261.349546018027;14561.92719036398;14956.401743930555;15023.774643380139;15110.924445378143;15205.935584508978;14832.480863169738;14928.253587320807;15142;15283.77074343908;15292.77060844066;14515.564533064025;14677.119372837664;14935.626609334853;14998.95007349475;15000.024998375071;9686.418814871131;9824.950875245579;10098.091171794575;10430.426326552113;10604.522796474332;11215.775684486427;11229.719257018312;11303.943480282545;11358.772824543628;12066.939665301617 17:42:57 INFO - PID 2498 | __end_tp_report 17:42:57 INFO - PID 2498 | __start_cc_report 17:42:57 INFO - PID 2498 | _x_x_mozilla_cycle_collect,452 17:42:57 INFO - PID 2498 | __end_cc_report 17:42:57 INFO - PID 2498 | __startTimestamp1496968977792__endTimestamp 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | ------- Summary: start ------- 17:42:57 INFO - PID 2498 | Number of tests: 6 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | [#0] dojo.html Cycles:165 Average:6091.73 Median:6316.08 stddev:736.49 (11.7%) stddev-sans-first:695.74 17:42:57 INFO - PID 2498 | Values: 2930.5 3179.2 3558.9 3594.1 3595.9 6092.6 6178.6 6213.7 6213.8 6220.6 6096.8 6114.9 6125.9 6139.8 6171.6 5295.2 5366.9 5377.2 5385.6 5390.4 5394.1 5470.0 5482.2 5484.8 5490.1 5300.3 5347.4 5377.0 5448.4 5456.1 5386.8 5398.7 5420.2 5420.9 5459.3 5353.8 5366.3 5382.4 5397.0 5398.0 6031.5 6246.7 6255.3 6264.9 6266.1 6394.0 6505.9 6593.1 6600.0 6606.9 6707.4 6711.1 6722.1 6739.9 6744.6 6266.9 6292.6 6316.1 6317.7 6338.1 6589.3 6681.9 6737.7 6812.4 6841.0 6181.9 6319.8 6522.9 6546.1 6553.9 6581.4 6679.5 6690.8 6691.3 6699.3 6550.7 6551.3 6554.6 6581.1 6583.4 6317.4 6396.0 6517.7 6521.4 6583.9 5314.8 5339.2 5340.7 5358.1 5369.8 4724.3 4731.5 4745.8 4749.1 4782.9 6119.7 6274.4 6291.6 6309.9 6312.1 5291.0 6724.1 6763.7 6765.6 6780.7 5109.9 6284.2 6311.8 6318.0 6326.2 4808.7 5749.8 5820.0 5901.4 5924.8 5874.7 6807.0 6844.1 6846.2 6871.4 5903.8 6741.5 6751.4 6764.5 6780.3 6653.2 6851.0 6914.8 6949.0 6957.7 6417.9 6450.3 6541.9 6564.2 6604.0 5925.8 6691.2 6710.9 6739.2 6753.5 6278.4 6309.4 6398.8 6481.5 6483.2 6465.3 6486.7 6496.3 6511.8 6545.1 6318.0 6384.6 6385.0 6395.1 6421.6 6316.9 6507.6 6510.5 6543.3 6559.9 6156.4 6200.8 6211.9 6229.0 6302.8 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | [#1] ext.html Cycles:180 Average:42783.58 Median:36048.96 stddev:72239.06 (200.4%) stddev-sans-first:72372.85 17:42:57 INFO - PID 2498 | Values: 772.9 793.2 831.8 1095.7 1150.2 37527.6 38922.2 39028.8 39041.0 39062.6 38869.2 39404.8 39440.2 39483.2 39579.6 7835.6 7892.4 8177.7 8449.2 8460.4 8445.9 8502.5 8557.5 8587.8 8601.6 8513.4 8554.7 8576.3 8610.2 8614.2 8540.0 8555.6 8582.6 8614.4 8634.0 8307.0 8359.6 8428.2 8428.6 8431.9 36935.4 37019.6 37076.4 37083.0 37243.3 3625.0 3673.0 3673.6 3675.5 3689.8 29935.3 30062.5 30120.7 30266.2 30302.5 3022.9 3049.1 3076.2 3118.4 3118.4 225364.0 228179.0 237435.8 298083.0 298304.0 40503.6 46847.5 46852.8 46995.5 47063.0 48544.0 48848.8 49070.0 49146.5 51050.0 14449.7 14533.0 14538.4 14558.6 14579.5 11570.0 11573.5 11645.2 11659.7 11670.3 14179.1 14242.4 14256.9 14281.6 14323.3 6043.4 6060.1 6071.5 6085.8 6087.4 895.6 979.0 996.8 1007.6 1021.9 21961.7 23161.5 24832.0 24839.0 24910.5 938.9 1098.8 1099.2 1164.0 1173.7 9536.5 11978.2 12515.6 12756.9 12786.2 274578.0 278204.6 311798.0 320748.0 321185.4 48725.3 48941.0 49021.0 49087.8 49305.0 1326.9 1480.6 1524.9 1527.8 1530.9 20978.7 22175.9 22320.3 22786.2 24074.8 36757.8 36823.8 36828.6 36869.4 37137.6 263996.0 264553.0 265099.0 265183.0 267131.0 34405.1 34613.8 35307.8 36048.5 36049.5 43683.7 44729.0 44898.0 45072.0 45136.0 29244.9 29573.0 29605.9 30098.8 31042.8 24346.8 28515.6 29849.3 30614.0 31072.2 36854.6 37826.0 37856.6 37961.2 38026.4 30114.2 30695.5 30746.4 30749.4 31689.9 23169.1 24751.5 24759.4 24909.5 24945.5 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | [#2] jquery.html Cycles:180 Average:40060.83 Median:12630.86 stddev:94874.42 (751.1%) stddev-sans-first:95093.93 17:42:57 INFO - PID 2498 | Values: 439.7 459.6 490.6 502.7 517.5 21748.5 22135.7 22843.7 22964.8 24293.9 24527.0 25018.1 25159.7 25255.4 25296.2 6968.9 7122.3 7128.1 7137.9 7152.6 6795.0 6886.8 6966.8 6971.3 7041.1 6883.3 6955.0 6968.3 6979.2 6999.0 6937.3 6953.3 6953.3 6971.9 6981.8 6792.0 6824.2 6825.1 6838.6 6838.9 30203.4 30815.8 30862.0 31111.5 31574.0 2218.5 2257.2 2264.9 2266.4 2270.4 15742.7 20012.2 20597.9 20613.0 20633.9 2074.5 2186.7 2297.0 2302.0 2354.5 167733.5 173999.0 194009.0 197595.0 206711.0 22700.2 24669.3 24848.6 26390.5 26656.6 44491.6 44527.8 44605.1 44609.3 44814.6 8307.5 10056.0 11095.7 11141.4 11700.8 8711.3 9003.9 9047.8 9056.5 9100.3 10342.2 12227.5 12452.4 12457.4 12514.0 4819.8 4905.7 4917.8 4925.8 4930.7 951.9 963.6 965.2 1011.2 1013.6 16843.1 20332.3 20747.5 20875.2 21407.3 966.9 972.3 975.3 1013.2 1015.7 9308.0 10136.7 10528.5 10545.9 10692.3 532299.0 551634.0 552213.0 554438.0 557886.0 137768.0 137967.0 143628.6 150634.7 159207.0 1101.9 1194.3 1228.3 1235.7 1254.2 22118.0 22604.3 23427.4 23718.5 23774.8 64727.0 66567.0 67493.3 67535.7 68862.0 89484.6 89977.7 90270.0 90273.5 90523.5 11386.2 11630.4 11657.7 11666.9 11678.9 17713.5 18334.3 18401.8 18587.4 18665.6 14801.5 14963.9 15140.1 15227.7 15276.5 13342.3 13561.5 13584.3 13636.7 13732.7 15189.5 15250.3 15562.2 15600.6 15660.8 11583.4 11608.4 11667.6 11693.4 11694.6 14957.9 15125.8 15223.8 15274.2 15295.5 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | [#3] mootools.html Cycles:180 Average:8066.55 Median:2700.49 stddev:5292.35 (196.0%) stddev-sans-first:5283.55 17:42:57 INFO - PID 2498 | Values: 1407.9 1451.8 1455.9 1460.6 1470.4 12733.7 12818.1 13446.3 13464.6 13677.2 13389.9 13530.1 13574.8 13601.9 13657.2 2711.4 2744.9 2749.3 2764.0 2770.5 2643.0 2689.2 2689.4 2690.4 2700.8 2652.5 2686.8 2693.1 2695.6 2700.2 2608.7 2643.8 2659.7 2662.2 2668.2 2221.8 2296.2 2617.6 2633.6 2648.6 12852.0 13258.3 13267.9 13270.5 13291.0 7331.7 7595.8 7647.0 7656.2 7668.0 4211.3 4339.0 4423.3 4423.5 4433.3 1262.2 1404.4 1431.0 1455.2 1457.5 18590.3 19654.9 19658.2 19685.4 19716.1 6321.6 6383.4 6423.5 6529.1 6539.2 13798.7 14046.7 14049.4 14057.9 14120.5 6855.8 7150.9 7264.0 7288.5 7315.1 6086.3 6461.2 6667.4 6727.4 6728.3 4781.5 4979.3 4980.6 5036.5 5042.5 1429.5 1449.6 1451.4 1468.8 1476.7 688.6 823.4 838.2 858.2 893.6 7312.5 7611.2 7704.3 7780.0 8024.4 993.3 1005.3 1011.7 1012.9 1047.1 4608.5 4734.6 4770.0 5129.2 5299.6 16598.3 17394.8 17396.6 17450.2 17857.9 17146.0 17298.4 17544.0 17558.9 17894.7 9277.4 9322.3 9353.5 9417.1 9439.0 6618.9 7612.8 7813.9 8113.0 8346.8 15543.3 15761.3 15995.4 16037.3 16060.7 15813.8 16125.8 16171.1 16354.3 16730.7 9596.2 9745.3 9745.6 9759.9 9785.4 11505.8 12066.0 12238.0 12304.3 12325.4 9010.3 9346.6 9389.9 10084.3 10114.2 9275.7 9283.9 9792.5 9877.2 10046.2 9053.6 9092.9 9144.4 9209.8 9260.7 6623.3 6851.7 6875.9 6920.7 7078.6 6586.4 6657.3 6831.9 6968.2 7059.0 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | [#4] prototype.html Cycles:180 Average:5498.09 Median:6010.38 stddev:1694.16 (28.2%) stddev-sans-first:1655.94 17:42:57 INFO - PID 2498 | Values: 446.1 452.1 463.7 466.3 473.2 5896.9 6233.4 6351.3 6426.4 6433.8 4816.0 6126.3 6198.1 6270.7 6284.3 3951.0 4012.7 4704.9 4760.3 4767.2 4346.1 4758.6 4773.6 4798.8 4838.0 4322.4 4701.2 4702.7 4710.6 4754.4 4231.5 4734.8 4750.5 4758.2 4776.5 3704.3 4116.8 4124.4 4146.5 4166.7 5518.9 5680.6 5738.4 6265.1 6280.0 4607.0 5654.8 5664.7 5667.2 5704.0 6796.2 6817.9 6830.8 6835.5 6838.7 4766.3 4766.9 4772.5 4777.7 4786.4 5861.3 6647.7 7261.8 7296.2 7299.8 4268.6 4443.6 4552.2 4747.4 4834.5 4221.5 4931.5 4944.7 4951.9 4973.5 5530.7 5591.8 5603.3 5613.9 5628.4 6836.2 6845.9 6879.7 6890.4 6902.0 1388.2 1653.9 1676.6 1681.7 1694.5 1111.2 1124.6 1150.3 1151.7 1161.9 6426.4 6456.9 6468.5 6473.5 6506.1 6250.7 6775.8 7020.4 7056.5 7095.8 6267.2 6423.7 6437.4 6438.8 6447.4 3344.8 3441.1 3664.1 3671.8 3727.7 6057.2 6359.6 6994.8 7268.9 7272.6 5902.7 7261.2 7264.5 7293.3 7306.4 7184.3 7250.0 7268.2 7296.3 7343.2 6003.2 6949.0 7041.1 7102.1 7239.7 6017.6 6691.9 6704.3 6709.1 6906.4 6060.3 7183.1 7250.7 7304.7 7329.1 4801.4 4820.3 4832.2 4849.9 4855.2 6917.8 7044.2 7068.2 7074.9 7085.1 5467.4 5758.7 6239.6 6357.0 6794.7 5936.3 5992.9 6769.2 6793.2 6889.9 5589.6 6613.7 6633.5 6662.9 6707.7 5408.2 5895.7 6100.2 6474.5 6763.9 5826.4 6623.2 6637.7 6641.6 6644.6 17:42:57 INFO - PID 2498 | 17:42:57 INFO - PID 2498 | [#5] yui.html Cycles:180 Average:24546.34 Median:15867.56 stddev:38646.89 (243.6%) stddev-sans-first:38714.27 17:42:57 INFO - PID 2498 | Values: 829.7 840.7 842.8 864.4 871.3 14131.4 14752.1 16383.0 16697.8 17020.2 15994.6 17874.7 17980.7 18012.1 18181.1 989.5 996.8 1015.1 1015.8 1018.1 1009.3 1013.7 1015.6 1015.8 1016.3 948.1 1049.5 1059.4 1059.7 1071.0 1048.1 1052.1 1053.6 1054.6 1055.7 761.2 762.0 762.3 762.7 771.8 16124.8 16519.3 16553.2 16554.2 16978.9 16318.8 17027.8 17301.8 17570.7 17915.3 13690.3 13715.2 13833.7 14901.7 15042.9 3370.7 3592.5 3618.8 3622.6 3628.8 152635.2 154182.2 154330.0 155784.0 156567.2 10838.5 13497.8 13523.7 13618.7 13823.8 22704.7 25905.0 26095.7 26365.0 26534.3 6613.7 6864.1 7557.0 7826.5 8076.5 3909.3 4002.0 4004.6 4024.2 4027.9 4522.7 4605.2 4608.4 4632.1 4643.4 1009.5 1019.3 1021.1 1023.6 1034.5 640.6 642.8 652.2 660.7 668.7 11228.4 11385.5 11939.9 13049.7 13309.5 657.3 659.3 669.4 677.3 684.4 5743.9 5843.6 5862.1 5887.5 6022.9 130290.0 132547.7 132812.3 134579.0 137939.0 113403.0 115089.3 116673.0 118882.4 121078.4 98406.0 99756.5 102706.0 104078.0 104433.0 10730.3 11271.9 11576.7 11772.9 11805.8 43052.0 44909.1 45181.3 46332.5 46615.8 46372.0 55562.4 58334.5 61875.0 61905.4 14516.3 14749.9 14760.9 14913.3 15004.2 15740.5 16091.6 16095.7 16097.7 16261.3 14561.9 14956.4 15023.8 15110.9 15205.9 14832.5 14928.3 15142.0 15283.8 15292.8 14515.6 14677.1 14935.6 14999.0 15000.0 9686.4 9825.0 10098.1 10430.4 10604.5 11215.8 11229.7 11303.9 11358.8 12066.9 17:42:57 INFO - PID 2498 | -------- Summary: end -------- 17:42:57 INFO - PID 2498 | 17:42:58 INFO - TEST-INFO | 2498: exit 0 17:42:58 INFO - TEST-OK | dromaeo_css | took 1142874ms 17:42:58 INFO - TEST-START | kraken 17:42:58 INFO - operating with platform_type : mac_ 17:42:58 INFO - Initialising browser for kraken test... 17:42:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58130/getInfo.html -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmperkzXQ/profile 17:42:58 INFO - TEST-INFO | started process 2505 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58130/getInfo.html -foreground) 17:43:32 INFO - TEST-INFO | 2505: killed by SIGTERM 17:43:32 INFO - Browser initialized. 17:43:32 INFO - Running cycle 1/1 for kraken test... 17:43:32 INFO - TEST-INFO | started process 2509 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmperkzXQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:43:34 INFO - PID 2509 | 2017-06-08 17:43:34.388 plugin-container[2510:39445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 17:43:34 INFO - PID 2509 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:34 INFO - PID 2509 | 2017-06-08 17:43:34.389 plugin-container[2510:39445] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 17:43:34 INFO - PID 2509 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:34 INFO - PID 2509 | 2017-06-08 17:43:34.389 plugin-container[2510:39445] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:43:34 INFO - PID 2509 | 2017-06-08 17:43:34.959 plugin-container[2511:39492] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 17:43:34 INFO - PID 2509 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:34 INFO - PID 2509 | 2017-06-08 17:43:34.959 plugin-container[2511:39492] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 17:43:34 INFO - PID 2509 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:43:34 INFO - PID 2509 | 2017-06-08 17:43:34.959 plugin-container[2511:39492] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:43:44 INFO - PID 2509 | RSS: Main: 253177856 17:43:44 INFO - PID 2509 | 17:43:47 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58130/tests/kraken/driver.html?testName=audio-beat-detection) 17:43:48 INFO - PID 2509 | RSS: Main: 265764864 17:43:48 INFO - PID 2509 | 17:43:50 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58130/tests/kraken/driver.html?testName=audio-dft) 17:43:51 INFO - PID 2509 | RSS: Main: 274690048 17:43:51 INFO - PID 2509 | 17:43:54 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58130/tests/kraken/driver.html?testName=audio-fft) 17:43:55 INFO - PID 2509 | RSS: Main: 276975616 17:43:55 INFO - PID 2509 | 17:43:57 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58130/tests/kraken/driver.html?testName=audio-oscillator) 17:43:58 INFO - PID 2509 | RSS: Main: 281436160 17:43:58 INFO - PID 2509 | 17:44:00 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58130/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:44:00 INFO - PID 2509 | RSS: Main: 282099712 17:44:00 INFO - PID 2509 | 17:44:03 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58130/tests/kraken/driver.html?testName=imaging-darkroom) 17:44:04 INFO - PID 2509 | RSS: Main: 290390016 17:44:04 INFO - PID 2509 | 17:44:07 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58130/tests/kraken/driver.html?testName=imaging-desaturate) 17:44:08 INFO - PID 2509 | RSS: Main: 289124352 17:44:08 INFO - PID 2509 | 17:44:11 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58130/tests/kraken/driver.html?testName=json-parse-financial) 17:44:12 INFO - PID 2509 | RSS: Main: 287830016 17:44:12 INFO - PID 2509 | 17:44:13 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58130/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:44:14 INFO - PID 2509 | RSS: Main: 287940608 17:44:14 INFO - PID 2509 | 17:44:15 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:44:16 INFO - PID 2509 | RSS: Main: 287789056 17:44:16 INFO - PID 2509 | 17:44:18 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:44:19 INFO - PID 2509 | RSS: Main: 287293440 17:44:19 INFO - PID 2509 | 17:44:22 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:44:23 INFO - PID 2509 | RSS: Main: 287584256 17:44:23 INFO - PID 2509 | 17:44:26 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:44:26 INFO - PID 2509 | RSS: Main: 287563776 17:44:26 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | Cycle 1(1): loaded http://localhost:58130/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58130/tests/kraken/driver.html?testName=ai-astar) 17:44:28 INFO - PID 2509 | RSS: Main: 289157120 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | __start_tp_report 17:44:28 INFO - PID 2509 | _x_x_mozilla_page_load 17:44:28 INFO - PID 2509 | _x_x_mozilla_page_load_details 17:44:28 INFO - PID 2509 | |i|pagename|runs| 17:44:28 INFO - PID 2509 | |0;ai-astar;112;118;112;170;164;116;118;114;116;116 17:44:28 INFO - PID 2509 | |1;audio-beat-detection;127;115;132;151;107;125;115;154;160;139 17:44:28 INFO - PID 2509 | |2;audio-dft;198;200;207;246;257;282;283;239;196;198 17:44:28 INFO - PID 2509 | |3;audio-fft;70;81;77;148;62;58;58;60;130;71 17:44:28 INFO - PID 2509 | |4;audio-oscillator;71;75;116;71;69;71;105;73;72;108 17:44:28 INFO - PID 2509 | |5;imaging-gaussian-blur;90;91;91;96;90;129;88;129;138;88 17:44:28 INFO - PID 2509 | |6;imaging-darkroom;97;124;132;152;155;97;121;99;99;98 17:44:28 INFO - PID 2509 | |7;imaging-desaturate;91;96;91;111;149;91;93;92;94;93 17:44:28 INFO - PID 2509 | |8;json-parse-financial;50;47;52;53;54;53;51;53;53;52 17:44:28 INFO - PID 2509 | |9;json-stringify-tinderbox;54;54;57;61;57;57;59;54;55;59 17:44:28 INFO - PID 2509 | |10;stanford-crypto-aes;85;85;84;96;108;92;91;89;118;111 17:44:28 INFO - PID 2509 | |11;stanford-crypto-ccm;136;147;140;137;128;143;146;147;150;138 17:44:28 INFO - PID 2509 | |12;stanford-crypto-pbkdf2;178;183;196;174;182;179;178;178;179;177 17:44:28 INFO - PID 2509 | |13;stanford-crypto-sha256-iterative;65;67;64;77;63;85;102;95;95;76 17:44:28 INFO - PID 2509 | __end_tp_report 17:44:28 INFO - PID 2509 | __start_cc_report 17:44:28 INFO - PID 2509 | _x_x_mozilla_cycle_collect,1210 17:44:28 INFO - PID 2509 | __end_cc_report 17:44:28 INFO - PID 2509 | __startTimestamp1496969068717__endTimestamp 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | ------- Summary: start ------- 17:44:28 INFO - PID 2509 | Number of tests: 14 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#0] ai-astar Cycles:10 Average:125.60 Median:117.00 stddev:21.97 (18.8%) stddev-sans-first:22.74 17:44:28 INFO - PID 2509 | Values: 112.0 118.0 112.0 170.0 164.0 116.0 118.0 114.0 116.0 116.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:135.50 stddev:18.11 (13.4%) stddev-sans-first:19.10 17:44:28 INFO - PID 2509 | Values: 127.0 115.0 132.0 151.0 107.0 125.0 115.0 154.0 160.0 139.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#2] audio-dft Cycles:10 Average:230.60 Median:242.50 stddev:35.29 (14.6%) stddev-sans-first:35.40 17:44:28 INFO - PID 2509 | Values: 198.0 200.0 207.0 246.0 257.0 282.0 283.0 239.0 196.0 198.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#3] audio-fft Cycles:10 Average:81.50 Median:66.00 stddev:31.60 (47.9%) stddev-sans-first:33.24 17:44:28 INFO - PID 2509 | Values: 70.0 81.0 77.0 148.0 62.0 58.0 58.0 60.0 130.0 71.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:71.00 stddev:18.59 (26.2%) stddev-sans-first:19.20 17:44:28 INFO - PID 2509 | Values: 71.0 75.0 116.0 71.0 69.0 71.0 105.0 73.0 72.0 108.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#5] imaging-gaussian-blur Cycles:10 Average:103.00 Median:90.00 stddev:20.28 (22.5%) stddev-sans-first:20.96 17:44:28 INFO - PID 2509 | Values: 90.0 91.0 91.0 96.0 90.0 129.0 88.0 129.0 138.0 88.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#6] imaging-darkroom Cycles:10 Average:117.40 Median:97.00 stddev:23.01 (23.7%) stddev-sans-first:23.19 17:44:28 INFO - PID 2509 | Values: 97.0 124.0 132.0 152.0 155.0 97.0 121.0 99.0 99.0 98.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#7] imaging-desaturate Cycles:10 Average:100.10 Median:92.50 stddev:18.19 (19.7%) stddev-sans-first:19.00 17:44:28 INFO - PID 2509 | Values: 91.0 96.0 91.0 111.0 149.0 91.0 93.0 92.0 94.0 93.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:53.00 stddev:2.04 (3.9%) stddev-sans-first:2.06 17:44:28 INFO - PID 2509 | Values: 50.0 47.0 52.0 53.0 54.0 53.0 51.0 53.0 53.0 52.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#9] json-stringify-tinderbox Cycles:10 Average:56.70 Median:57.00 stddev:2.45 (4.3%) stddev-sans-first:2.40 17:44:28 INFO - PID 2509 | Values: 54.0 54.0 57.0 61.0 57.0 57.0 59.0 54.0 55.0 59.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#10] stanford-crypto-aes Cycles:10 Average:95.90 Median:87.00 stddev:12.15 (14.0%) stddev-sans-first:12.23 17:44:28 INFO - PID 2509 | Values: 85.0 85.0 84.0 96.0 108.0 92.0 91.0 89.0 118.0 111.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:144.50 stddev:6.68 (4.6%) stddev-sans-first:6.82 17:44:28 INFO - PID 2509 | Values: 136.0 147.0 140.0 137.0 128.0 143.0 146.0 147.0 150.0 138.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:179.00 stddev:6.02 (3.4%) stddev-sans-first:6.32 17:44:28 INFO - PID 2509 | Values: 178.0 183.0 196.0 174.0 182.0 179.0 178.0 178.0 179.0 177.0 17:44:28 INFO - PID 2509 | 17:44:28 INFO - PID 2509 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:76.50 stddev:14.57 (19.0%) stddev-sans-first:14.56 17:44:28 INFO - PID 2509 | Values: 65.0 67.0 64.0 77.0 63.0 85.0 102.0 95.0 95.0 76.0 17:44:28 INFO - PID 2509 | -------- Summary: end -------- 17:44:28 INFO - PID 2509 | 17:44:29 INFO - TEST-INFO | 2509: exit 0 17:44:29 INFO - TEST-OK | kraken | took 91405ms 17:44:29 INFO - SUITE-END | took 1234s 17:44:29 INFO - Completed test suite (00:20:34) 17:44:29 INFO - javascript benchmark 17:44:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8341.833707465774, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2930.4578652949212, 3179.2210908327456, 3558.8754393596228, 3594.06554295883, 3595.85616575337, 6092.573519853612, 6178.5983911045805, 6213.689315534217, 6213.813585592428, 6220.595661282019, 6096.786612468553, 6114.87770244596, 6125.9387406125925, 6139.768135342631, 6171.598846075013, 5295.179247216668, 5366.9463305367035, 5377.193420986844, 5385.596080293974, 5390.434004429518, 5394.109971854639, 5469.972650136764, 5482.17767334901, 5484.7531861066345, 5490.121580547113, 5300.337457817773, 5347.438518955492, 5377.0, 5448.400675925645, 5456.072467680484, 5386.811461598864, 5398.676079435247, 5420.214068960018, 5420.91868621971, 5459.2902922619805, 5353.759080841332, 5366.302380690524, 5382.354117505885, 5397.0, 5397.973010134924, 6031.45716885478, 6246.656433896134, 6255.343188965138, 6264.9373506265265, 6266.122742815964, 6393.968030159819, 6505.902411463831, 6593.076969224312, 6600.0, 6606.86786264272, 6707.3628005339415, 6711.094002309675, 6722.1261236039, 6739.898901516482, 6744.5616034957575, 6266.905996410058, 6292.622442653455, 6316.084167795644, 6317.747290108436, 6338.080978258219, 6589.275179730226, 6681.9331806682285, 6737.663116844138, 6812.386885180357, 6840.965795170992, 6181.876362472725, 6319.810405687745, 6522.934770652328, 6546.116274302908, 6553.868922621521, 6581.440577550866, 6679.532432729662, 6690.799276021728, 6691.297413771581, 6699.330066993359, 6550.672466376757, 6551.344865513506, 6554.573952693059, 6581.111549940792, 6583.440407565315, 6317.368263173646, 6396.0, 6517.739290428424, 6521.413072823374, 6583.868322633521, 5314.7608357624, 5339.199120132012, 5340.706261155674, 5358.142697168512, 5369.7583608737705, 4724.338592597039, 4731.4558825735485, 4745.762711864393, 4749.145153872336, 4782.904341913212, 6119.746288422617, 6274.372562743689, 6291.622502649856, 6309.936900630935, 6312.053192021233, 5291.021161085211, 6724.092247546617, 6763.695633696496, 6765.560238584476, 6780.660966951633, 5109.932824366633, 6284.151639528605, 6311.842203944938, 6318.0, 6326.2092238470195, 4808.663393562487, 5749.770009199585, 5819.970900145557, 5901.409859014064, 5924.822255332347, 5874.706264686663, 6806.965965170142, 6844.1444819397575, 6846.178458585002, 6871.415929646036, 5903.848749493807, 6741.460683145435, 6751.392374686302, 6764.458843292623, 6780.32196780318, 6653.201615806134, 6850.965745171442, 6914.757983470555, 6948.965255173692, 6957.652117394211, 6417.935820641734, 6450.258220304725, 6541.934580654323, 6564.212294524687, 6603.966980165068, 5925.755591325697, 6691.197056353269, 6710.899336510054, 6739.157605299338, 6753.493487988519, 6278.4035516625845, 6309.3690630936535, 6398.776042838478, 6481.546291759531, 6483.222013358427, 6465.321141280286, 6486.675666216576, 6496.252930913006, 6511.804645860631, 6545.050967609765, 6317.96841015792, 6384.6169229846355, 6385.010323399881, 6395.136656551306, 6421.550491465552, 6316.905246421215, 6507.642079685474, 6510.511711621552, 6543.280239173592, 6559.901601475886, 6156.384361563987, 6200.782972595937, 6211.9129152397245, 6229.034499652561, 6302.779402720883], "value": 6041.699226210051, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.9072511298644, 793.1711361627099, 831.7629475599456, 1095.7315457712857, 1150.2293463379535, 37527.62472375276, 38922.221555568874, 39028.82913512599, 39041.0, 39062.60937390625, 38869.22261554774, 39404.80297598515, 39440.21119577614, 39483.210335793265, 39579.604203957955, 7835.6473958671995, 7892.40806939479, 8177.672893084269, 8449.155084491533, 8460.36547258955, 8445.873311900295, 8502.489850608952, 8557.48655080694, 8587.785305367384, 8601.61292741825, 8513.361497887652, 8554.657813687476, 8576.271016963534, 8610.225079742822, 8614.224719775219, 8540.017897941723, 8555.614997325105, 8582.613782379745, 8614.396992210548, 8633.956830215871, 8307.0, 8359.623816928191, 8428.241458268785, 8428.578571071423, 8431.873521897176, 36935.445968310494, 37019.629803702155, 37076.44385334222, 37083.0, 37243.255134897154, 3625.0212442640604, 3673.008287762316, 3673.5775385830702, 3675.5221821163077, 3689.797061161635, 29935.251618709488, 30062.54906176409, 30120.69879301201, 30266.243343916358, 30302.545461818092, 3022.909312720622, 3049.130997665655, 3076.1848110250803, 3118.3607360491046, 3118.3919135768533, 225364.0, 228179.0, 237435.81282093652, 298083.0, 298304.0, 40503.59496405057, 46847.531524685, 46852.7657361711, 46995.53004469912, 47063.0, 48544.0, 48848.75575622099, 49070.0, 49146.508534914195, 51049.979000419786, 14449.711005780036, 14533.0, 14538.41846326135, 14558.636034099232, 14579.469155738707, 11569.958703716538, 11573.479193436315, 11645.184837061324, 11659.650210493699, 11670.299782013106, 14179.078359906573, 14242.43030278798, 14256.928715356356, 14281.571552853431, 14323.283835808168, 6043.395660433922, 6060.091156245027, 6071.51427885768, 6085.817425477243, 6087.391260873966, 895.6462197432108, 978.9510524473748, 996.7757254617653, 1007.6120693532896, 1021.8824835144054, 21961.682299062108, 23161.53676926452, 24832.00671973101, 24839.0, 24910.5017899641, 938.9108633984681, 1098.81869491533, 1099.197585762398, 1163.959261425846, 1173.6889724223088, 9536.52317384128, 11978.161528693083, 12515.56195533152, 12756.936215318863, 12786.168899021533, 274578.0, 278204.60897695384, 311798.0, 320748.0, 321185.3940730281, 48725.26912096321, 48941.0, 49021.0, 49087.75456122697, 49305.0, 1326.9384492406036, 1480.637243875236, 1524.9161296128818, 1527.8243002054905, 1530.9464168754264, 20978.685319719913, 22175.889120554293, 22320.330390088322, 22786.202482913017, 24074.759252407253, 36757.816210918776, 36823.815880920425, 36828.631713682524, 36869.44695829618, 37137.62862371342, 263996.0, 264553.0, 265099.0, 265183.0, 267131.0, 34405.13987150241, 34613.82693086519, 35307.823460883556, 36048.4592731104, 36049.459258111674, 43683.68948931537, 44729.0, 44898.0, 45072.0, 45136.0, 29244.853775730986, 29572.964946226777, 29605.85197074001, 30098.849505752332, 31042.84478577683, 24346.75653243445, 28515.572266415606, 29849.25376865596, 30614.0, 31072.22319442032, 36854.63145368512, 37826.0, 37856.62143378531, 37961.240775184895, 38026.429603555414, 30114.24714382158, 30695.539566906067, 30746.385072298875, 30749.385012299183, 31689.890853820005, 23169.07323707033, 24751.486088216625, 24759.38101547476, 24909.501809963338, 24945.50108997846], "value": 17301.265940276375, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.72517176764524, 459.6024438860386, 490.6467343512672, 502.74815708884887, 517.464424320828, 21748.45628859279, 22135.66796498052, 22843.657345139836, 22964.770352296473, 24293.878530607366, 24527.018919243208, 25018.124365647207, 25159.74840251597, 25255.368615784613, 25296.241112766602, 6968.860622787542, 7122.252163522833, 7128.14462264529, 7137.928620713804, 7152.570845749246, 6795.0, 6886.827829304258, 6966.79099627012, 6971.3028697130385, 7041.119860017498, 6883.27725588811, 6954.965225173867, 6968.338007889242, 6979.162500499921, 6998.985147153659, 6937.340952609494, 6953.269906659779, 6953.269906659803, 6971.895421568655, 6981.79054628362, 6791.966040169817, 6824.18109826819, 6825.078614387044, 6838.623875686835, 6838.863222735567, 30203.395932081454, 30815.845920770476, 30862.0, 31111.533327000114, 31574.0, 2218.511927375991, 2257.1535674122206, 2264.8980795864227, 2266.3880752196815, 2270.3642979965634, 15742.661873740746, 20012.199512019502, 20597.867117308542, 20613.0, 20633.896830515903, 2074.5228597422524, 2186.650135978252, 2296.954060918789, 2302.0, 2354.540864531417, 167733.48399774014, 173999.0, 194009.0, 197595.0, 206710.9999999985, 22700.205492807672, 24669.259922202364, 24848.627270590954, 26390.47219055608, 26656.60015099775, 44491.555084448744, 44527.77736111299, 44605.10789784186, 44609.330860037124, 44814.55185448169, 8307.501549907025, 10056.0, 11095.72260693489, 11141.387223702774, 11700.765984680429, 8711.34664900135, 9003.864942025875, 9047.77380565478, 9056.456612603264, 9100.271978241724, 10342.172626189888, 12227.510899563918, 12452.377381130907, 12457.439415226336, 12514.014360671124, 4819.807207711652, 4905.730184839868, 4917.803287868445, 4925.7783399747095, 4930.704157750546, 951.8667386565747, 963.5726156015941, 965.1988849255101, 1011.1960991012163, 1013.5793645637126, 16843.073630950414, 20332.288369906983, 20747.481312967295, 20875.164993400092, 21407.250746223806, 966.8790428481215, 972.3193764364972, 975.2929126383457, 1013.2096964367657, 1015.6952914125881, 9307.953460232655, 10136.746581335525, 10528.473576321154, 10545.9472702636, 10692.305000174963, 532299.0, 551634.0, 552213.0, 554438.0, 557886.0, 137768.0, 137967.0, 143628.5637143636, 150634.7404788929, 159207.0, 1101.8787933327487, 1194.26552670109, 1228.342836582424, 1235.721962558417, 1254.2161149281699, 22118.0, 22604.321870343916, 23427.414314642272, 23718.52562948766, 23774.811259437305, 64727.0, 66567.00149497665, 67493.3250667487, 67535.66232168808, 68861.96707049297, 89484.5525772367, 89977.65033524371, 90270.0, 90273.54863225904, 90523.54738226267, 11386.202965792647, 11630.418479076181, 11657.650270491898, 11666.88333116658, 11678.88321116778, 17713.468595942144, 18334.266629334677, 18401.815981840547, 18587.44237672872, 18665.6266874659, 14801.481948131765, 14963.925180374028, 15140.091594504365, 15227.695446091238, 15276.465323713617, 13342.266175360448, 13561.525346612822, 13584.32078396096, 13636.727265454834, 13732.725345493234, 15189.468368607046, 15250.31373588169, 15562.221888905737, 15600.609984750472, 15660.843391566394, 11583.362915039754, 11608.419579020847, 11667.6499705009, 11693.356865372147, 11694.649160525198, 14957.850421495647, 15125.773113403087, 15223.847761522244, 15274.15992120444, 15295.541133766004], "value": 12675.595950716031, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.866252705993, 1451.7677171652538, 1455.9226172632254, 1460.583733635914, 1470.4118352658936, 12733.681657958545, 12818.102732808708, 13446.327683615817, 13464.59606211814, 13677.179369237852, 13389.866101339008, 13530.052896297238, 13574.796378054338, 13601.93199034006, 13657.248851313174, 2711.3628297350106, 2744.890204391822, 2749.257700420885, 2763.9723602763916, 2770.515159847029, 2643.0, 2689.2066840282064, 2689.40833016737, 2690.367763575568, 2700.7839372850126, 2652.5225459417297, 2686.838789672626, 2693.138195777352, 2695.636089127972, 2700.203439985209, 2608.7391260873856, 2643.8413695178256, 2659.6941351744604, 2662.1614191529607, 2668.1862032080207, 2221.766714494987, 2296.242240060776, 2617.5942728877053, 2633.565461698817, 2648.602709593557, 12852.0, 13258.337083145901, 13267.933660331733, 13270.469181232738, 13291.003174761932, 7331.706731730725, 7595.81010474737, 7647.044119485065, 7656.157822639505, 7668.0, 4211.263028969948, 4339.0, 4423.270160423526, 4423.469183697945, 4433.268510695763, 1262.1543565810928, 1404.4101477379525, 1431.0340520148857, 1455.2069122328357, 1457.460739526382, 18590.349337773114, 19654.90172549128, 19658.213671453268, 19685.40943771689, 19716.112774925165, 6321.55749097568, 6383.425491705676, 6423.454006409414, 6529.053287273318, 6539.215294164635, 13798.65503362424, 14046.71906561863, 14049.438022479191, 14057.85942140586, 14120.50578229757, 6855.771874140924, 7150.892736608955, 7263.963680181565, 7288.526245794006, 7315.085614298213, 6086.330503644595, 6461.19235095613, 6667.366600172976, 6727.394534491919, 6728.293529179464, 4781.545753153445, 4979.278004689299, 4980.551750342486, 5036.471170527115, 5042.470540593258, 1429.5068201470476, 1449.6375906023495, 1451.397669966973, 1468.8237411510688, 1476.7489526780473, 688.6246995387426, 823.4318320358933, 838.237204144238, 858.2447446247387, 893.5710858787818, 7312.488125831141, 7611.1627720950655, 7704.26809453101, 7779.961100194463, 8024.398170137147, 993.3493561717087, 1005.3314545826943, 1011.7066050845173, 1012.8632634594383, 1047.0628787235382, 4608.493065762764, 4734.573888350065, 4769.950610865604, 5129.204973229155, 5299.602529810202, 16598.336066557204, 17394.82605173932, 17396.565085872702, 17450.214740336716, 17857.910710446624, 17145.971241725536, 17298.394556190473, 17544.0, 17558.91220543915, 17894.731579026324, 9277.396969196981, 9322.25421966241, 9353.485558294495, 9417.058294170529, 9438.95280523593, 6618.900716489353, 7612.809679757829, 7813.882791758242, 8113.0, 8346.833063338821, 15543.300551474984, 15761.290741916411, 15995.440159594358, 16037.278322475284, 16060.67878642444, 15813.841861581237, 16125.75811362807, 16171.110588917723, 16354.264058117651, 16730.665386692443, 9596.18432433251, 9745.269104817025, 9745.610175593181, 9759.853602195832, 9785.36395134314, 11505.849415058761, 12066.0, 12238.0, 12304.26174429537, 12325.383730813244, 9010.279177665901, 9346.579403926975, 9389.859152112587, 10084.344517606332, 10114.241431892491, 9275.72172834816, 9283.907160928575, 9792.461414622265, 9877.160441362277, 10046.24653151002, 9053.59258833341, 9092.909070909207, 9144.405613635092, 9209.76975575616, 9260.722178334661, 6623.3376662333385, 6851.725930962705, 6875.896861547181, 6920.688569014507, 7078.610676412847, 6586.4401525870835, 6657.334266573498, 6831.9316806833285, 6968.198657154492, 7059.0], "value": 5998.1934376638865, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.09888026187105, 452.09129649404696, 463.6684770389171, 466.3424571354391, 473.1719490890941, 5896.877124235277, 6233.40782625651, 6351.301356850754, 6426.389492998158, 6433.774817881375, 4815.975920120403, 6126.295476020248, 6198.078595635357, 6270.686465676721, 6284.277308109557, 3950.980245098785, 4012.699047571429, 4704.882377940553, 4760.309755085511, 4767.1657459944545, 4346.130773845228, 4758.619310455155, 4773.618110551148, 4798.7840547175465, 4838.0, 4322.4164737760475, 4701.224297990844, 4702.717836929778, 4710.62315014798, 4754.358161648185, 4231.492220933507, 4734.810607575693, 4750.501197374295, 4758.167320718878, 4776.522347765213, 3704.2776658551593, 4116.773577453238, 4124.360724087741, 4146.543880173179, 4166.708330416872, 5518.862028449281, 5680.573956953203, 5738.368779434259, 6265.091561723524, 6279.968600157015, 4606.976965115153, 5654.773809047674, 5664.745086471077, 5667.206591077253, 5703.971480142573, 6796.184457865038, 6817.863642727118, 6830.829229269259, 6835.521513494057, 6838.7264509419565, 4766.30888521166, 4766.928496072562, 4772.475027746945, 4777.737224452654, 4786.401699787527, 5861.267341582302, 6647.700853461557, 7261.782146535613, 7296.233895440955, 7299.781006569811, 4268.637165840876, 4443.622292105143, 4552.157850797546, 4747.359106520576, 4834.516548345137, 4221.493420789464, 4931.50958765821, 4944.703317800943, 4951.900961980812, 4973.50264973507, 5530.723463826873, 5591.804286849941, 5603.299587551556, 5613.933352662981, 5628.409017053233, 6836.179658440919, 6845.8973115403305, 6879.690413931385, 6890.415204502992, 6901.965490172617, 1388.2017839742189, 1653.9421120260733, 1676.6227598790174, 1681.7477378393094, 1694.5001224638515, 1111.2054880760295, 1124.6288724720823, 1150.2580835361312, 1151.7330935970365, 1161.8780028096928, 6426.3573642635365, 6456.903146452807, 6468.544577470032, 6473.482121430275, 6506.121673574007, 6250.656213908188, 6775.796726098225, 7020.403265722471, 7056.48385033579, 7095.751648692271, 6267.1539342189135, 6423.710933008026, 6437.420632143129, 6438.8390290243115, 6447.4210315874025, 3344.7993120412852, 3441.0537102296566, 3664.13892735207, 3671.81640917953, 3727.6702061211745, 6057.153707395835, 6359.554831161867, 6994.790156295319, 7268.927310726931, 7272.63636818157, 5902.734376953047, 7261.2012678605315, 7264.527805692719, 7293.343599076109, 7306.415486761046, 7184.317489838457, 7249.963750181215, 7268.164161121539, 7296.306850849161, 7343.228960959129, 6003.189569408249, 6949.0, 7041.084658994298, 7102.076730025064, 7239.746608868875, 6017.578769486094, 6691.862383394932, 6704.262531121475, 6709.127813384229, 6906.412954898889, 6060.333363329942, 7183.138023437221, 7250.746223882139, 7304.68515667185, 7329.120505539369, 4801.423829140525, 4820.276958456259, 4832.226843705131, 4849.854504364875, 4855.223164293696, 6917.757878474431, 7044.189918159477, 7068.222495525385, 7074.929250707427, 7085.114360704912, 5467.39858615544, 5758.740856661377, 6239.594426362453, 6357.046443033397, 6794.72821087151, 5936.28764548257, 5992.861356342455, 6769.221539523018, 6793.150856142982, 6889.862202756017, 5589.608727389043, 6613.702383392855, 6633.502487313374, 6662.866742665216, 6707.66461676924, 5408.161734931092, 5895.7052147393315, 6100.176476175837, 6474.514411419256, 6763.898541521783, 5826.41735826414, 6623.178625877922, 6637.734490620514, 6641.634710090992, 6644.60132392058], "value": 4989.657321835952, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.7303376402671, 840.701550949413, 842.7556008757457, 864.4294765454802, 871.3290766110093, 14131.364088616012, 14752.114873107621, 16383.017018978855, 16697.83302166978, 17020.234089465972, 15994.600134996603, 17874.731908809295, 17980.730289045674, 18012.09939503026, 18181.09094545274, 989.4706332112304, 996.8205722969863, 1015.1117771949545, 1015.8476228565737, 1018.1040684197895, 1009.2934945538116, 1013.7313611892854, 1015.5937624950042, 1015.8374660054393, 1016.2682868334787, 948.1371951524114, 1049.490996866519, 1059.390850261099, 1059.7403636109163, 1070.9518071686755, 1048.09863517375, 1052.0951981296118, 1053.5838343854211, 1054.6361505280704, 1055.6516349604613, 761.2197497564985, 762.0246085011195, 762.2872614105829, 762.6758627583255, 771.7877583664481, 16124.75812862808, 16519.33922643105, 16553.172341382884, 16554.17229138538, 16978.915105424516, 16318.75521867173, 17027.829721702874, 17301.82698173027, 17570.736438953427, 17915.283388664437, 13690.315484225848, 13715.245661488614, 13833.723325533534, 14901.70196596062, 15042.924785376112, 3370.7303415726683, 3592.4790905318846, 3618.782873027627, 3622.601513833476, 3628.76413033152, 152635.2368238163, 154182.22908885495, 154330.0, 155784.0, 156567.21716391458, 10838.512266948006, 13497.797533037012, 13523.729525409437, 13618.659533511543, 13823.79264311036, 22704.65943010856, 25905.0, 26095.73904260971, 26365.0, 26534.33664158373, 6613.735450582019, 6864.107666003488, 7557.0, 7826.452148349561, 8076.4750291230885, 3909.276783795007, 4001.979990100031, 4004.579519150447, 4024.215278020758, 4027.8791636250958, 4522.706024108423, 4605.217113090783, 4608.354830323758, 4632.050429661911, 4643.419572553431, 1009.5204777730667, 1019.2864994503877, 1021.0759262867117, 1023.5854478936043, 1034.4569101221919, 640.6322501459417, 642.7814543055383, 652.2205963873221, 660.6696651674163, 668.6957434367351, 11228.382438965771, 11385.487653055632, 11939.88060119405, 13049.739005220032, 13309.534166304327, 657.2769953051605, 659.261626977788, 669.3774789445765, 677.2888467109515, 684.3772167327808, 5743.885122297531, 5843.5909486336395, 5862.149988251765, 5887.499562537147, 6022.909656355158, 130290.0, 132547.67452325547, 132812.33593831968, 134579.0, 137939.0, 113403.0, 115089.27366089515, 116673.0, 118882.40558797323, 121078.3946080264, 98406.0, 99756.50121749345, 102706.0, 104078.0, 104433.0, 10730.302530335503, 11271.887281127083, 11576.710582235512, 11772.881576250242, 11805.76388472243, 43052.0, 44909.10181796411, 45181.32228016648, 46332.53667463282, 46615.766921166534, 46372.0, 55562.444375557345, 58334.54163645773, 61875.0, 61905.38094618996, 14516.346764395415, 14749.926250368679, 14760.926195368955, 14913.25433728331, 15004.174770387297, 15740.527784166494, 16091.597710057342, 16095.67808643844, 16097.678046438772, 16261.349546018027, 14561.92719036398, 14956.401743930555, 15023.774643380139, 15110.924445378143, 15205.935584508978, 14832.480863169738, 14928.253587320807, 15142.0, 15283.77074343908, 15292.77060844066, 14515.564533064025, 14677.119372837664, 14935.626609334853, 14998.95007349475, 15000.024998375071, 9686.418814871131, 9824.950875245579, 10098.091171794575, 10430.426326552113, 10604.522796474332, 11215.775684486427, 11229.719257018312, 11303.943480282545, 11358.772824543628, 12066.939665301617], "value": 8496.561139409807, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.7000000000005, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 118.0, 112.0, 170.0, 164.0, 116.0, 118.0, 114.0, 116.0, 116.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 115.0, 132.0, 151.0, 107.0, 125.0, 115.0, 154.0, 160.0, 139.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 200.0, 207.0, 246.0, 257.0, 282.0, 283.0, 239.0, 196.0, 198.0], "value": 230.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 77.0, 148.0, 62.0, 58.0, 58.0, 60.0, 130.0, 71.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 116.0, 71.0, 69.0, 71.0, 105.0, 73.0, 72.0, 108.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 91.0, 96.0, 90.0, 129.0, 88.0, 129.0, 138.0, 88.0], "value": 103.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 124.0, 132.0, 152.0, 155.0, 97.0, 121.0, 99.0, 99.0, 98.0], "value": 117.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 91.0, 111.0, 149.0, 91.0, 93.0, 92.0, 94.0, 93.0], "value": 100.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 47.0, 52.0, 53.0, 54.0, 53.0, 51.0, 53.0, 53.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 57.0, 61.0, 57.0, 57.0, 59.0, 54.0, 55.0, 59.0], "value": 56.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 96.0, 108.0, 92.0, 91.0, 89.0, 118.0, 111.0], "value": 95.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 147.0, 140.0, 137.0, 128.0, 143.0, 146.0, 147.0, 150.0, 138.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 183.0, 196.0, 174.0, 182.0, 179.0, 178.0, 178.0, 179.0, 177.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 64.0, 77.0, 63.0, 85.0, 102.0, 95.0, 95.0, 76.0], "value": 78.9, "unit": "score"}]}]} 17:44:30 INFO - Return code: 0 17:44:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:44:30 INFO - # TBPL SUCCESS # 17:44:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:44:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:44:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:44:30 INFO - 2017-06-08 17:44:30.111 screenresolution[2517:39886] starting screenresolution argv=screenresolution get 17:44:30 INFO - 2017-06-08 17:44:30.114 screenresolution[2517:39886] Display 0: 1600x1200x32@60 17:44:30 INFO - 2017-06-08 17:44:30.119 screenresolution[2518:39889] starting screenresolution argv=screenresolution list 17:44:30 INFO - Available Modes on Display 0 17:44:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:44:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:44:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:44:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:44:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:44:30 INFO - Intel Iris: 17:44:30 INFO - Chipset Model: Intel Iris 17:44:30 INFO - Type: GPU 17:44:30 INFO - Bus: Built-In 17:44:30 INFO - VRAM (Dynamic, Max): 1536 MB 17:44:30 INFO - Vendor: Intel (0x8086) 17:44:30 INFO - Device ID: 0x0a2e 17:44:30 INFO - Revision ID: 0x0009 17:44:30 INFO - Displays: 17:44:30 INFO - Display: 17:44:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:44:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:44:30 INFO - Display Serial Number: 1600x1200 60 17:44:30 INFO - Main Display: Yes 17:44:30 INFO - Mirror: Off 17:44:30 INFO - Online: Yes 17:44:30 INFO - Rotation: Supported 17:44:30 INFO - Return code: 0 17:44:30 INFO - Running post-action listener: _resource_record_post_action 17:44:30 INFO - [mozharness: 2017-06-09 00:44:30.361188Z] Finished run-tests step (success) 17:44:30 INFO - Running post-run listener: _resource_record_post_run 17:44:30 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 1942016; Write bytes: 573093888; Read time: 15; Write time: 1928 17:44:30 INFO - TinderboxPrint: CPU usage
53.3% 17:44:30 INFO - TinderboxPrint: I/O read bytes / time
1,942,016 / 15 17:44:30 INFO - TinderboxPrint: I/O write bytes / time
573,093,888 / 1,928 17:44:30 INFO - TinderboxPrint: CPU idle
2,346.6 (46.7%) 17:44:30 INFO - TinderboxPrint: CPU system
1,022.0 (20.3%) 17:44:30 INFO - TinderboxPrint: CPU user
1,661.0 (33.0%) 17:44:30 INFO - TinderboxPrint: Swap in / out
248,532,992 / 0 17:44:30 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247814656; Write bytes: 249309184; Read time: 18266; Write time: 474 17:44:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:44:30 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 938496; Write bytes: 322260992; Read time: 10; Write time: 1446 17:44:30 INFO - Running post-run listener: _upload_blobber_files 17:44:30 INFO - Blob upload gear active. 17:44:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:44:30 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 17:44:30 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'] 17:44:30 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 17:44:31 INFO - (blobuploader) - INFO - Open directory for files ... 17:44:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:44:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:44:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:33 INFO - (blobuploader) - INFO - Done attempting. 17:44:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:44:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:44:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:34 INFO - (blobuploader) - INFO - Done attempting. 17:44:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:44:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:44:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:35 INFO - (blobuploader) - INFO - Done attempting. 17:44:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:44:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:44:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:37 INFO - (blobuploader) - INFO - Done attempting. 17:44:37 INFO - (blobuploader) - INFO - Iteration through files over. 17:44:37 INFO - Return code: 0 17:44:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:44:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:44:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1aa27878589cc8269437e826925a0ed82885aa32fdf41dac502970a5237eba6f08762e676ebae502ba731a18e4ad406fef759941cd8c106c8d0c1acec8abf0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf863c6f5962d04820e1bf005765ca1875ca01ef6e4aa92e8796e5d3c4679be5f0934f98c44604d47e73444e572b7270c41dd395b3d766ce09f87eb4c96daa9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931728bcba433967bde3db40f2653a8a1dd602a4e29098d8e0052032dc97b531faa208b517b7eafbb520ca7923313d998efd124de16082b1de6cb817f437a78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2695d38047bb5431eab48931000b05e8cbd88559d12744faaccc1fa73e211df9128dda6b5769c67bf64cd943989d0904b5b84258e93919e1240853e86bba1982"} 17:44:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:44:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:44:37 INFO - Contents: 17:44:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1aa27878589cc8269437e826925a0ed82885aa32fdf41dac502970a5237eba6f08762e676ebae502ba731a18e4ad406fef759941cd8c106c8d0c1acec8abf0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf863c6f5962d04820e1bf005765ca1875ca01ef6e4aa92e8796e5d3c4679be5f0934f98c44604d47e73444e572b7270c41dd395b3d766ce09f87eb4c96daa9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931728bcba433967bde3db40f2653a8a1dd602a4e29098d8e0052032dc97b531faa208b517b7eafbb520ca7923313d998efd124de16082b1de6cb817f437a78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2695d38047bb5431eab48931000b05e8cbd88559d12744faaccc1fa73e211df9128dda6b5769c67bf64cd943989d0904b5b84258e93919e1240853e86bba1982"} 17:44:37 INFO - Running post-run listener: copy_logs_to_upload_dir 17:44:37 INFO - Copying logs to upload dir... 17:44:37 INFO - mkdir: /builds/slave/test/build/upload/logs 17:44:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.629364 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-08 17:44:37.979309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 17:44:37.982625) ========= 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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/d1aa27878589cc8269437e826925a0ed82885aa32fdf41dac502970a5237eba6f08762e676ebae502ba731a18e4ad406fef759941cd8c106c8d0c1acec8abf0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf863c6f5962d04820e1bf005765ca1875ca01ef6e4aa92e8796e5d3c4679be5f0934f98c44604d47e73444e572b7270c41dd395b3d766ce09f87eb4c96daa9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931728bcba433967bde3db40f2653a8a1dd602a4e29098d8e0052032dc97b531faa208b517b7eafbb520ca7923313d998efd124de16082b1de6cb817f437a78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2695d38047bb5431eab48931000b05e8cbd88559d12744faaccc1fa73e211df9128dda6b5769c67bf64cd943989d0904b5b84258e93919e1240853e86bba1982"} build_url:https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011412 build_url: 'https://queue.taskcluster.net/v1/task/bHvIlpttSMqv9HQj20HzIA/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/d1aa27878589cc8269437e826925a0ed82885aa32fdf41dac502970a5237eba6f08762e676ebae502ba731a18e4ad406fef759941cd8c106c8d0c1acec8abf0d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ebf863c6f5962d04820e1bf005765ca1875ca01ef6e4aa92e8796e5d3c4679be5f0934f98c44604d47e73444e572b7270c41dd395b3d766ce09f87eb4c96daa9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0931728bcba433967bde3db40f2653a8a1dd602a4e29098d8e0052032dc97b531faa208b517b7eafbb520ca7923313d998efd124de16082b1de6cb817f437a78", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2695d38047bb5431eab48931000b05e8cbd88559d12744faaccc1fa73e211df9128dda6b5769c67bf64cd943989d0904b5b84258e93919e1240853e86bba1982"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 17:44:38.016617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 17:44:38.016936) ========= 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.ppYL4LTgah/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.9Oiqm5ytsi/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005397 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 17:44:38.067293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 17:44:38.067603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 17:44:38.067998) ========= ========= Total master_lag: 0.24 =========