builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0365 starttime: 1495950197.33 results: success (0) revision: 6a235092bda7c20cb83651d3404284fe36d04c1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:17.331834) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:17.332469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:17.332744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:17.387461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-27 22:43:17.387801) ========= 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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-27 22:43:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 54.3M=0s 2017-05-27 22:43:18 (54.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.654591 ========= master_lag: 2.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-27 22:43:20.327191) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:20.327482) ========= 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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.040946 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:20.441747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-27 22:43:20.442076) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6a235092bda7c20cb83651d3404284fe36d04c1b --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 6a235092bda7c20cb83651d3404284fe36d04c1b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-27 22:43:20,592 truncating revision to first 12 chars 2017-05-27 22:43:20,592 Setting DEBUG logging. 2017-05-27 22:43:20,592 attempt 1/10 2017-05-27 22:43:20,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6a235092bda7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-27 22:43:21,980 unpacking tar archive at: mozilla-central-6a235092bda7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.673830 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-27 22:43:22.190067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:22.190553) ========= script_repo_revision: 6a235092bda7c20cb83651d3404284fe36d04c1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:22.190928) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:22.191190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 22:43:22.210325) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-27 22:43:22.210707) ========= /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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:43:22 INFO - MultiFileLogger online at 20170527 22:43:22 in /builds/slave/test 22:43:22 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 22:43:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:43:22 INFO - {'append_to_log': False, 22:43:22 INFO - 'base_work_dir': '/builds/slave/test', 22:43:22 INFO - 'blob_upload_branch': 'Firefox', 22:43:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:43:22 INFO - 'branch': 'Firefox', 22:43:22 INFO - 'buildbot_json_path': 'buildprops.json', 22:43:22 INFO - 'config_files': ('talos/mac_config.py',), 22:43:22 INFO - 'default_actions': ('clobber', 22:43:22 INFO - 'read-buildbot-config', 22:43:22 INFO - 'download-and-extract', 22:43:22 INFO - 'populate-webroot', 22:43:22 INFO - 'create-virtualenv', 22:43:22 INFO - 'install', 22:43:22 INFO - 'setup-mitmproxy', 22:43:22 INFO - 'run-tests'), 22:43:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:43:22 INFO - 'download_minidump_stackwalk': True, 22:43:22 INFO - 'download_symbols': 'ondemand', 22:43:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:43:22 INFO - 'tooltool.py': '/tools/tooltool.py', 22:43:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:43:22 INFO - '/tools/misc-python/virtualenv.py')}, 22:43:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:43:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:43:22 INFO - 'gecko_profile': False, 22:43:22 INFO - 'gecko_profile_interval': 0, 22:43:22 INFO - 'installer_path': 'installer.exe', 22:43:22 INFO - 'log_level': 'info', 22:43:22 INFO - 'log_name': 'talos', 22:43:22 INFO - 'log_to_console': True, 22:43:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:43:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:43:22 INFO - 'opt_config_files': (), 22:43:22 INFO - 'pip_index': False, 22:43:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:43:22 INFO - 'cmd': ('bash', 22:43:22 INFO - '-c', 22:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:43:22 INFO - 'enabled': True, 22:43:22 INFO - 'halt_on_failure': False, 22:43:22 INFO - 'name': 'check_screen_resolution'},), 22:43:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:43:22 INFO - 'cmd': ('bash', 22:43:22 INFO - '-c', 22:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:43:22 INFO - 'enabled': True, 22:43:22 INFO - 'halt_on_failure': False, 22:43:22 INFO - 'name': 'check_screen_resolution'},), 22:43:22 INFO - 'run_cmd_checks_enabled': True, 22:43:22 INFO - 'suite': 'dromaeojs-e10s', 22:43:22 INFO - 'system_bits': '32', 22:43:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:43:22 INFO - 'title': 't-yosemite-r7-0365', 22:43:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:43:22 INFO - 'use_talos_json': True, 22:43:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:43:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:43:22 INFO - 'work_dir': 'build'} 22:43:22 INFO - [mozharness: 2017-05-28 05:43:22.419784Z] Running clobber step. 22:43:22 INFO - Running pre-action listener: _resource_record_pre_action 22:43:22 INFO - Running main action method: clobber 22:43:22 INFO - rmtree: /builds/slave/test/build 22:43:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:43:24 INFO - Running post-action listener: _resource_record_post_action 22:43:24 INFO - [mozharness: 2017-05-28 05:43:24.957405Z] Finished clobber step (success) 22:43:24 INFO - [mozharness: 2017-05-28 05:43:24.957534Z] Running read-buildbot-config step. 22:43:24 INFO - Running pre-action listener: _resource_record_pre_action 22:43:24 INFO - Running main action method: read_buildbot_config 22:43:24 INFO - Using buildbot properties: 22:43:24 INFO - { 22:43:24 INFO - "project": "", 22:43:24 INFO - "product": "firefox", 22:43:24 INFO - "who": "philringnalda@gmail.com", 22:43:24 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 22:43:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:43:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 22:43:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:43:24 INFO - "stage_platform": "macosx64", 22:43:24 INFO - "basedir": "/builds/slave/test", 22:43:24 INFO - "buildnumber": 167, 22:43:24 INFO - "platform": "yosemite_r7", 22:43:24 INFO - "parent_task_id": "CONU9Z3YSUuRJXwbRc1j4g", 22:43:24 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:43:24 INFO - "slavebuilddir": "test", 22:43:24 INFO - "taskId": "WvvI8KlETKiLEsZTG_eklQ", 22:43:24 INFO - "branch": "mozilla-central", 22:43:24 INFO - "script_repo_revision": "production", 22:43:24 INFO - "revision": "6a235092bda7c20cb83651d3404284fe36d04c1b", 22:43:24 INFO - "slavename": "t-yosemite-r7-0365", 22:43:24 INFO - "repo_path": "mozilla-central" 22:43:24 INFO - } 22:43:24 INFO - Finding installer, test and symbols from parent task. 22:43:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WvvI8KlETKiLEsZTG_eklQ'}, attempt #1 22:43:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WvvI8KlETKiLEsZTG_eklQ'}, attempt #1 22:43:26 INFO - Task dependencies: WJtppNDUTdOc6o0wJN4npw CONU9Z3YSUuRJXwbRc1j4g 22:43:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g'}, attempt #1 22:43:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:43:26 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/test_packages.json 22:43:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/target.crashreporter-symbols.zip 22:43:26 INFO - Running post-action listener: _resource_record_post_action 22:43:26 INFO - [mozharness: 2017-05-28 05:43:26.696586Z] Finished read-buildbot-config step (success) 22:43:26 INFO - [mozharness: 2017-05-28 05:43:26.696706Z] Running download-and-extract step. 22:43:26 INFO - Running pre-action listener: _resource_record_pre_action 22:43:26 INFO - Running main action method: download_and_extract 22:43:26 INFO - mkdir: /builds/slave/test/build/tests 22:43: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')]} 22:43:26 INFO - trying https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:43:26 INFO - Downloading https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/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 22:43:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/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 22:43:27 INFO - Downloaded 1325 bytes. 22:43:27 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:43:27 INFO - Using the following test package requirements: 22:43:27 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:43:27 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:43:27 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:43:27 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:43:27 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'jsshell-mac.zip'], 22:43:27 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:43:27 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:43:27 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:43:27 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:43:27 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:43:27 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:43:27 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:43:27 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:43:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:43:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:43:27 INFO - Fetch https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:43:29 INFO - Content-Length response header: 57628893 22:43:29 INFO - Bytes received: 57628893 22:43:47 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 22:43:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:43:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:43:47 INFO - Fetch https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:43:49 INFO - Content-Length response header: 57628893 22:43:49 INFO - Bytes received: 57628893 22:44:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:44:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:44:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:44:08 INFO - Content-Length response header: 14447425 22:44:08 INFO - Bytes received: 14447425 22:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:08 INFO - trying https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:44:08 INFO - Downloading https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:44:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/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 22:44:10 INFO - Downloaded 67397724 bytes. 22:44:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:44:10 INFO - mkdir: /builds/slave/test/properties 22:44:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:44:10 INFO - Writing to file /builds/slave/test/properties/build_url 22:44:10 INFO - Contents: 22:44:10 INFO - build_url:https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:44:10 INFO - Running post-action listener: _resource_record_post_action 22:44:10 INFO - Running post-action listener: set_extra_try_arguments 22:44:10 INFO - [mozharness: 2017-05-28 05:44:10.297885Z] Finished download-and-extract step (success) 22:44:10 INFO - [mozharness: 2017-05-28 05:44:10.298048Z] Running populate-webroot step. 22:44:10 INFO - Running pre-action listener: _resource_record_pre_action 22:44:10 INFO - Running main action method: populate_webroot 22:44:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:44:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:44:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:44:10 INFO - '--tppagecycles', 22:44:10 INFO - '7'], 22:44:10 INFO - 'tests': ['tsvgm']}}, 22:44:10 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:44:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:44:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:44:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:44:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:44:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['damp', 'tps']}, 22:44:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'tests': ['damp', 'tps']}, 22:44:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['dromaeo_dom']}, 22:44:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:44:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:44:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:44:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 22:44:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['a11yr', 22:44:10 INFO - 'ts_paint', 22:44:10 INFO - 'tpaint', 22:44:10 INFO - 'sessionrestore', 22:44:10 INFO - 'sessionrestore_no_auto_restore', 22:44:10 INFO - 'tabpaint']}, 22:44:10 INFO - 'other-e10s': {'tests': ['a11yr', 22:44:10 INFO - 'ts_paint', 22:44:10 INFO - 'tpaint', 22:44:10 INFO - 'sessionrestore', 22:44:10 INFO - 'sessionrestore_no_auto_restore', 22:44:10 INFO - 'tabpaint']}, 22:44:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:44:10 INFO - 'ts_paint', 22:44:10 INFO - 'tpaint', 22:44:10 INFO - 'sessionrestore', 22:44:10 INFO - 'sessionrestore_no_auto_restore', 22:44:10 INFO - 'tabpaint']}, 22:44:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:44:10 INFO - 'ts_paint', 22:44:10 INFO - 'tpaint', 22:44:10 INFO - 'sessionrestore', 22:44:10 INFO - 'sessionrestore_no_auto_restore', 22:44:10 INFO - 'tabpaint']}, 22:44:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['a11yr', 22:44:10 INFO - 'ts_paint', 22:44:10 INFO - 'tpaint', 22:44:10 INFO - 'sessionrestore', 22:44:10 INFO - 'sessionrestore_no_auto_restore', 22:44:10 INFO - 'tabpaint']}, 22:44:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['a11yr', 22:44:10 INFO - 'ts_paint', 22:44:10 INFO - 'tpaint', 22:44:10 INFO - 'sessionrestore', 22:44:10 INFO - 'sessionrestore_no_auto_restore', 22:44:10 INFO - 'tabpaint']}, 22:44:10 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:44:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:44:10 INFO - 'bloom_basic_ref']}, 22:44:10 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 22:44:10 INFO - 'talos_options': ['--mitmproxy', 22:44:10 INFO - 'mitmproxy-recording-1.mp', 22:44:10 INFO - '--firstNonBlankPaint'], 22:44:10 INFO - 'tests': ['Quantum_1']}, 22:44:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['tsvgx', 22:44:10 INFO - 'tsvgr_opacity', 22:44:10 INFO - 'tart', 22:44:10 INFO - 'tscrollx', 22:44:10 INFO - 'cart', 22:44:10 INFO - 'tsvg_static']}, 22:44:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:44:10 INFO - 'tsvgr_opacity', 22:44:10 INFO - 'tart', 22:44:10 INFO - 'tscrollx', 22:44:10 INFO - 'cart', 22:44:10 INFO - 'tsvg_static']}, 22:44:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'talos_options': ['--disable-e10s'], 22:44:10 INFO - 'tests': ['tp5o']}, 22:44:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:44:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'talos_options': ['--disable-e10s', 22:44:10 INFO - '--xperf_path', 22:44:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:10 INFO - 'tests': ['tp5n']}, 22:44:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:44:10 INFO - 'talos_options': ['--xperf_path', 22:44:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:44:10 INFO - 'tests': ['tp5n']}}, 22:44:10 INFO - 'talos.zip': {'path': '', 22:44:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:44:10 INFO - Running post-action listener: _resource_record_post_action 22:44:10 INFO - [mozharness: 2017-05-28 05:44:10.319536Z] Finished populate-webroot step (success) 22:44:10 INFO - [mozharness: 2017-05-28 05:44:10.319663Z] Running create-virtualenv step. 22:44:10 INFO - Running pre-action listener: _resource_record_pre_action 22:44:10 INFO - Running main action method: create_virtualenv 22:44:10 INFO - Creating virtualenv /builds/slave/test/build/venv 22:44:10 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 22:44:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:44:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:44:10 INFO - Using real prefix '/tools/python27' 22:44:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:44:11 INFO - Installing distribute.............................................................................................................................................................................................done. 22:44:14 INFO - Installing pip.................done. 22:44:14 INFO - Return code: 0 22:44:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:44:14 INFO - Reading from file tmpfile_stdout 22:44:14 INFO - Output received: 22:44:14 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) 22:44:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:44:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:14 INFO - 'HOME': '/Users/cltbld', 22:44:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:14 INFO - 'LOGNAME': 'cltbld', 22:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:14 INFO - 'MOZ_NO_REMOTE': '1', 22:44:14 INFO - 'NO_EM_RESTART': '1', 22:44:14 INFO - 'PAGER': '/bin/cat', 22:44:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:14 INFO - 'PWD': '/builds/slave/test', 22:44:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:14 INFO - 'SHELL': '/bin/bash', 22:44:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:14 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:14 INFO - 'USER': 'cltbld', 22:44:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:14 INFO - 'XPC_FLAGS': '0x0', 22:44:14 INFO - 'XPC_SERVICE_NAME': '0', 22:44:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:14 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 22:44:14 INFO - Cleaning up... 22:44:14 INFO - Return code: 0 22:44:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:44:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:14 INFO - 'HOME': '/Users/cltbld', 22:44:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:14 INFO - 'LOGNAME': 'cltbld', 22:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:14 INFO - 'MOZ_NO_REMOTE': '1', 22:44:14 INFO - 'NO_EM_RESTART': '1', 22:44:14 INFO - 'PAGER': '/bin/cat', 22:44:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:14 INFO - 'PWD': '/builds/slave/test', 22:44:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:14 INFO - 'SHELL': '/bin/bash', 22:44:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:14 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:14 INFO - 'USER': 'cltbld', 22:44:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:14 INFO - 'XPC_FLAGS': '0x0', 22:44:14 INFO - 'XPC_SERVICE_NAME': '0', 22:44:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:14 INFO - Downloading/unpacking psutil>=3.1.1 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:44:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:44:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:44:17 INFO - Installing collected packages: psutil 22:44:17 INFO - Running setup.py install for psutil 22:44:17 INFO - building 'psutil._psutil_osx' extension 22:44:17 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 22:44:17 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 22:44:18 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 22:44:18 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 22:44:18 INFO - building 'psutil._psutil_posix' extension 22:44:18 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 22:44:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:44:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:44:18 INFO - ^ 22:44:18 INFO - 1 warning generated. 22:44:18 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 22:44:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:44:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:44:18 INFO - Successfully installed psutil 22:44:18 INFO - Cleaning up... 22:44:18 INFO - Return code: 0 22:44:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44: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')]} 22:44:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:44: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 mozsystemmonitor==0.3 22:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:18 INFO - 'HOME': '/Users/cltbld', 22:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:18 INFO - 'LOGNAME': 'cltbld', 22:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:18 INFO - 'MOZ_NO_REMOTE': '1', 22:44:18 INFO - 'NO_EM_RESTART': '1', 22:44:18 INFO - 'PAGER': '/bin/cat', 22:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:18 INFO - 'PWD': '/builds/slave/test', 22:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:18 INFO - 'SHELL': '/bin/bash', 22:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:18 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:18 INFO - 'USER': 'cltbld', 22:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:18 INFO - 'XPC_FLAGS': '0x0', 22:44:18 INFO - 'XPC_SERVICE_NAME': '0', 22:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:44: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 22:44: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 22:44: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 22:44: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 22:44:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:44:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:44:20 INFO - Installing collected packages: mozsystemmonitor 22:44:20 INFO - Running setup.py install for mozsystemmonitor 22:44:20 INFO - Successfully installed mozsystemmonitor 22:44:20 INFO - Cleaning up... 22:44:20 INFO - Return code: 0 22:44:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:20 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:20 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 22:44:20 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 22:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:20 INFO - 'HOME': '/Users/cltbld', 22:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:20 INFO - 'LOGNAME': 'cltbld', 22:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:20 INFO - 'MOZ_NO_REMOTE': '1', 22:44:20 INFO - 'NO_EM_RESTART': '1', 22:44:20 INFO - 'PAGER': '/bin/cat', 22:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:20 INFO - 'PWD': '/builds/slave/test', 22:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:20 INFO - 'SHELL': '/bin/bash', 22:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:20 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:20 INFO - 'USER': 'cltbld', 22:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:20 INFO - 'XPC_FLAGS': '0x0', 22:44:20 INFO - 'XPC_SERVICE_NAME': '0', 22:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:20 INFO - Downloading/unpacking jsonschema==2.5.1 22:44: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 22:44: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 22:44: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 22:44: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 22:44:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:44:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:44:22 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 22:44:22 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 22:44:22 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 22:44:22 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 22:44:22 INFO - Downloading functools32-3.2.3-2.tar.gz 22:44:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:44:22 INFO - warning: no files found matching '*.txt' 22:44:22 INFO - no previously-included directories found matching 'build' 22:44:22 INFO - no previously-included directories found matching 'dist' 22:44:22 INFO - no previously-included directories found matching '.git*' 22:44:22 INFO - Installing collected packages: jsonschema, functools32 22:44:22 INFO - Running setup.py install for functools32 22:44:22 INFO - warning: no files found matching '*.txt' 22:44:22 INFO - no previously-included directories found matching 'build' 22:44:22 INFO - no previously-included directories found matching 'dist' 22:44:22 INFO - no previously-included directories found matching '.git*' 22:44:22 INFO - Successfully installed jsonschema functools32 22:44:22 INFO - Cleaning up... 22:44:22 INFO - Return code: 0 22:44:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44: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')]} 22:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:22 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:22 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 22:44:22 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 22:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:22 INFO - 'HOME': '/Users/cltbld', 22:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:22 INFO - 'LOGNAME': 'cltbld', 22:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:22 INFO - 'MOZ_NO_REMOTE': '1', 22:44:22 INFO - 'NO_EM_RESTART': '1', 22:44:22 INFO - 'PAGER': '/bin/cat', 22:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:22 INFO - 'PWD': '/builds/slave/test', 22:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:22 INFO - 'SHELL': '/bin/bash', 22:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:22 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:22 INFO - 'USER': 'cltbld', 22:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:22 INFO - 'XPC_FLAGS': '0x0', 22:44:22 INFO - 'XPC_SERVICE_NAME': '0', 22:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:44:22 INFO - Cleaning up... 22:44:22 INFO - Return code: 0 22:44:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44: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')]} 22:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:22 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:22 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 22:44:22 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 22:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:22 INFO - 'HOME': '/Users/cltbld', 22:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:22 INFO - 'LOGNAME': 'cltbld', 22:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:22 INFO - 'MOZ_NO_REMOTE': '1', 22:44:22 INFO - 'NO_EM_RESTART': '1', 22:44:22 INFO - 'PAGER': '/bin/cat', 22:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:22 INFO - 'PWD': '/builds/slave/test', 22:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:22 INFO - 'SHELL': '/bin/bash', 22:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:22 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:22 INFO - 'USER': 'cltbld', 22:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:22 INFO - 'XPC_FLAGS': '0x0', 22:44:22 INFO - 'XPC_SERVICE_NAME': '0', 22:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:23 INFO - Downloading/unpacking blobuploader==1.2.4 22:44:23 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 22:44:23 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 22:44:23 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 22:44:23 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 22:44:24 INFO - Downloading blobuploader-1.2.4.tar.gz 22:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:44:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:44:24 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 22:44:24 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 22:44:24 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 22:44:24 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 22:44:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:44:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:44:25 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 22:44:25 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 22:44:25 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 22:44:25 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 22:44:25 INFO - Downloading docopt-0.6.1.tar.gz 22:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:44:25 INFO - Installing collected packages: blobuploader, requests, docopt 22:44:25 INFO - Running setup.py install for blobuploader 22:44:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:44:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:44:25 INFO - Running setup.py install for requests 22:44:26 INFO - Running setup.py install for docopt 22:44:26 INFO - Successfully installed blobuploader requests docopt 22:44:26 INFO - Cleaning up... 22:44:26 INFO - Return code: 0 22:44:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44: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')]} 22:44:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44: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')]} 22:44:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:26 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:26 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 22:44:26 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 22:44:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:26 INFO - 'HOME': '/Users/cltbld', 22:44:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:26 INFO - 'LOGNAME': 'cltbld', 22:44:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:26 INFO - 'MOZ_NO_REMOTE': '1', 22:44:26 INFO - 'NO_EM_RESTART': '1', 22:44:26 INFO - 'PAGER': '/bin/cat', 22:44:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:26 INFO - 'PWD': '/builds/slave/test', 22:44:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:26 INFO - 'SHELL': '/bin/bash', 22:44:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:26 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:26 INFO - 'USER': 'cltbld', 22:44:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:26 INFO - 'XPC_FLAGS': '0x0', 22:44:26 INFO - 'XPC_SERVICE_NAME': '0', 22:44:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:44:26 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-q72cXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:44:26 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-jpwJt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:44:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:44:26 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-KGlvo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-9gG6Ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-R2f9Ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-rupLGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-TDxBfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-kw0cna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-F90_5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-T2QyB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-elajRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-NDk9Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:44:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:44:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-UWBNsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:44:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-HLTYTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-d4tcAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:44:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IESPmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:44:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:44:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-z3SjQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:44:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:44:28 INFO - Running setup.py install for manifestparser 22:44:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:44:28 INFO - Running setup.py install for mozcrash 22:44:28 INFO - Running setup.py install for mozdebug 22:44:28 INFO - Running setup.py install for mozdevice 22:44:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Running setup.py install for mozfile 22:44:29 INFO - Running setup.py install for mozhttpd 22:44:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Running setup.py install for mozinfo 22:44:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Running setup.py install for mozInstall 22:44:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:44:29 INFO - Running setup.py install for mozleak 22:44:29 INFO - Running setup.py install for mozlog 22:44:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:44:30 INFO - Running setup.py install for moznetwork 22:44:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:44:30 INFO - Running setup.py install for mozprocess 22:44:30 INFO - Running setup.py install for mozprofile 22:44:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:44:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:44:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:44:30 INFO - Running setup.py install for mozrunner 22:44:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:44:30 INFO - Running setup.py install for mozscreenshot 22:44:30 INFO - Running setup.py install for moztest 22:44:30 INFO - Running setup.py install for mozversion 22:44:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:44:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:44:31 INFO - Cleaning up... 22:44:31 INFO - Return code: 0 22:44:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:31 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:31 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 22:44:31 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 22:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:31 INFO - 'HOME': '/Users/cltbld', 22:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:31 INFO - 'LOGNAME': 'cltbld', 22:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:31 INFO - 'MOZ_NO_REMOTE': '1', 22:44:31 INFO - 'NO_EM_RESTART': '1', 22:44:31 INFO - 'PAGER': '/bin/cat', 22:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:31 INFO - 'PWD': '/builds/slave/test', 22:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:31 INFO - 'SHELL': '/bin/bash', 22:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:31 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:31 INFO - 'USER': 'cltbld', 22:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:31 INFO - 'XPC_FLAGS': '0x0', 22:44:31 INFO - 'XPC_SERVICE_NAME': '0', 22:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:44:31 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-hoDQUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:44:31 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)) 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:44:31 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ZPzRcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:44:31 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)) 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:44:31 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-aOzxor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:44:31 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)) 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:44:31 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-kUjYBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:44:31 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)) 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:44:31 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Wpptpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:44:31 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)) 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:44:31 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-a1z39F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:44:31 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)) 22:44:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-40xVj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-bLeL1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-FUuguI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-BIz1qs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-_KaNPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-v1bpWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-dNpmcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-f6s7GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-XGQ515-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:32 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)) 22:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:44:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ZwFEec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:44:33 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)) 22:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:44:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tmxuK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:44:33 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)) 22:44:33 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)) 22:44:33 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)) 22:44:33 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)) 22:44:33 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)) 22:44:33 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)) 22:44:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:44:33 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 22:44:33 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 22:44:33 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 22:44:33 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 22:44:34 INFO - Downloading blessings-1.6.tar.gz 22:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:44:34 INFO - Installing collected packages: blessings 22:44:34 INFO - Running setup.py install for blessings 22:44:34 INFO - Successfully installed blessings 22:44:34 INFO - Cleaning up... 22:44:34 INFO - Return code: 0 22:44:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:35 INFO - Reading from file tmpfile_stdout 22:44:35 INFO - Current package versions: 22:44:35 INFO - blessings == 1.6 22:44:35 INFO - blobuploader == 1.2.4 22:44:35 INFO - docopt == 0.6.1 22:44:35 INFO - functools32 == 3.2.3-2 22:44:35 INFO - jsonschema == 2.5.1 22:44:35 INFO - manifestparser == 1.1 22:44:35 INFO - mozInstall == 1.12 22:44:35 INFO - mozcrash == 1.0 22:44:35 INFO - mozdebug == 0.1 22:44:35 INFO - mozdevice == 0.51 22:44:35 INFO - mozfile == 1.2 22:44:35 INFO - mozhttpd == 0.7 22:44:35 INFO - mozinfo == 0.9 22:44:35 INFO - mozleak == 0.1 22:44:35 INFO - mozlog == 3.4 22:44:35 INFO - moznetwork == 0.27 22:44:35 INFO - mozprocess == 0.25 22:44:35 INFO - mozprofile == 0.28 22:44:35 INFO - mozrunner == 6.13 22:44:35 INFO - mozscreenshot == 0.1 22:44:35 INFO - mozsystemmonitor == 0.3 22:44:35 INFO - moztest == 0.8 22:44:35 INFO - mozversion == 1.4 22:44:35 INFO - psutil == 3.1.1 22:44:35 INFO - requests == 1.2.3 22:44:35 INFO - wsgiref == 0.1.2 22:44:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:35 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:35 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 22:44:35 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 22:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:35 INFO - 'HOME': '/Users/cltbld', 22:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:35 INFO - 'LOGNAME': 'cltbld', 22:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:35 INFO - 'MOZ_NO_REMOTE': '1', 22:44:35 INFO - 'NO_EM_RESTART': '1', 22:44:35 INFO - 'PAGER': '/bin/cat', 22:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:35 INFO - 'PWD': '/builds/slave/test', 22:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:35 INFO - 'SHELL': '/bin/bash', 22:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:35 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:35 INFO - 'USER': 'cltbld', 22:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:35 INFO - 'XPC_FLAGS': '0x0', 22:44:35 INFO - 'XPC_SERVICE_NAME': '0', 22:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 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)) 22:44:35 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:44:35 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 22:44:35 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 22:44:35 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 22:44:35 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 22:44:36 INFO - Downloading marionette_driver-1.4.0.tar.gz 22:44:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 22:44:36 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)) 22:44:36 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)) 22:44:36 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)) 22:44:36 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)) 22:44:36 INFO - Installing collected packages: marionette-driver 22:44:36 INFO - Running setup.py install for marionette-driver 22:44:37 INFO - Successfully installed marionette-driver 22:44:37 INFO - Cleaning up... 22:44:37 INFO - Return code: 0 22:44:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:44:37 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')]} 22:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:37 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')]} 22:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:37 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 0x102f63c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f67768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9209558200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, '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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 22:44:37 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 22:44:37 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 22:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:37 INFO - 'HOME': '/Users/cltbld', 22:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:37 INFO - 'LOGNAME': 'cltbld', 22:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:37 INFO - 'MOZ_NO_REMOTE': '1', 22:44:37 INFO - 'NO_EM_RESTART': '1', 22:44:37 INFO - 'PAGER': '/bin/cat', 22:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:37 INFO - 'PWD': '/builds/slave/test', 22:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:37 INFO - 'SHELL': '/bin/bash', 22:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:37 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:37 INFO - 'USER': 'cltbld', 22:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:37 INFO - 'XPC_FLAGS': '0x0', 22:44:37 INFO - 'XPC_SERVICE_NAME': '0', 22:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:44:37 INFO - Cleaning up... 22:44:37 INFO - Return code: 0 22:44:37 INFO - Running post-action listener: _resource_record_post_action 22:44:37 INFO - Running post-action listener: _start_resource_monitoring 22:44:37 INFO - Starting resource monitoring. 22:44:37 INFO - [mozharness: 2017-05-28 05:44:37.436725Z] Finished create-virtualenv step (success) 22:44:37 INFO - [mozharness: 2017-05-28 05:44:37.437700Z] Running install step. 22:44:37 INFO - Running pre-action listener: _resource_record_pre_action 22:44:37 INFO - Running main action method: install 22:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:37 INFO - Reading from file tmpfile_stdout 22:44:37 INFO - Detecting whether we're running mozinstall >=1.0... 22:44:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:44:37 INFO - Reading from file tmpfile_stdout 22:44:37 INFO - Output received: 22:44:37 INFO - Usage: mozinstall [options] installer 22:44:37 INFO - Options: 22:44:37 INFO - -h, --help show this help message and exit 22:44:37 INFO - -d DEST, --destination=DEST 22:44:37 INFO - Directory to install application into. [default: 22:44:37 INFO - "/builds/slave/test"] 22:44:37 INFO - --app=APP Application being installed. [default: firefox] 22:44:37 INFO - mkdir: /builds/slave/test/build/application 22:44:37 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'] 22:44:37 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 22:44:58 INFO - Reading from file tmpfile_stdout 22:44:58 INFO - Output received: 22:44:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:44:58 INFO - Running post-action listener: _resource_record_post_action 22:44:58 INFO - [mozharness: 2017-05-28 05:44:58.886649Z] Finished install step (success) 22:44:58 INFO - [mozharness: 2017-05-28 05:44:58.886781Z] Running setup-mitmproxy step. 22:44:58 INFO - Running pre-action listener: _resource_record_pre_action 22:44:58 INFO - Running main action method: setup_mitmproxy 22:44:58 INFO - Skipping: mitmproxy is not required 22:44:58 INFO - Running post-action listener: _resource_record_post_action 22:44:58 INFO - [mozharness: 2017-05-28 05:44:58.887344Z] Finished setup-mitmproxy step (success) 22:44:58 INFO - [mozharness: 2017-05-28 05:44:58.887453Z] Running run-tests step. 22:44:58 INFO - Running pre-action listener: _resource_record_pre_action 22:44:58 INFO - Running main action method: run_tests 22:44:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:58 INFO - 2017-05-27 22:44:58.902 screenresolution[1642:8457] starting screenresolution argv=screenresolution get 22:44:58 INFO - 2017-05-27 22:44:58.904 screenresolution[1642:8457] Display 0: 1600x1200x32@60 22:44:58 INFO - 2017-05-27 22:44:58.910 screenresolution[1643:8460] starting screenresolution argv=screenresolution list 22:44:58 INFO - Available Modes on Display 0 22:44:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:59 INFO - Intel Iris: 22:44:59 INFO - Chipset Model: Intel Iris 22:44:59 INFO - Type: GPU 22:44:59 INFO - Bus: Built-In 22:44:59 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:59 INFO - Vendor: Intel (0x8086) 22:44:59 INFO - Device ID: 0x0a2e 22:44:59 INFO - Revision ID: 0x0009 22:44:59 INFO - Displays: 22:44:59 INFO - Display: 22:44:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:59 INFO - Display Serial Number: 1600x1200 60 22:44:59 INFO - Main Display: Yes 22:44:59 INFO - Mirror: Off 22:44:59 INFO - Online: Yes 22:44:59 INFO - Rotation: Supported 22:44:59 INFO - Return code: 0 22:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:44:59 INFO - Python 2.7.3 22:44:59 INFO - Return code: 0 22:44:59 INFO - grabbing minidump binary from tooltool 22:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:44:59 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 0x102eecc60>, '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 0x102c81cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102caabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:44:59 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 22:44:59 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 22:44:59 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 22:44:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:44:59 INFO - Return code: 0 22:44:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:44:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:44:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:44:59 INFO - ENV: RUST_BACKTRACE is now 1 22:44:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:44:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:44:59 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/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 22:44:59 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/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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 22:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yp3zh4Ey2z/Render', 22:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:44:59 INFO - 'HOME': '/Users/cltbld', 22:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:44:59 INFO - 'LOGNAME': 'cltbld', 22:44:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:44:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:59 INFO - 'MOZ_NO_REMOTE': '1', 22:44:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:44:59 INFO - 'NO_EM_RESTART': '1', 22:44:59 INFO - 'PAGER': '/bin/cat', 22:44:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:59 INFO - 'PWD': '/builds/slave/test', 22:44:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:44:59 INFO - 'RUST_BACKTRACE': '1', 22:44:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:44:59 INFO - 'SHELL': '/bin/bash', 22:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JKPpf8WxqS/Listeners', 22:44:59 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 22:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:44:59 INFO - 'USER': 'cltbld', 22:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:59 INFO - 'XPC_FLAGS': '0x0', 22:44:59 INFO - 'XPC_SERVICE_NAME': '0', 22:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:44:59 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/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 22:44:59 INFO - mozversion application_buildid: 20170527213642 22:44:59 INFO - mozversion application_changeset: 6a235092bda7c20cb83651d3404284fe36d04c1b 22:44:59 INFO - mozversion application_display_name: Nightly 22:44:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:44:59 INFO - mozversion application_name: Firefox 22:44:59 INFO - mozversion application_remotingname: firefox 22:44:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:44:59 INFO - mozversion application_vendor: Mozilla 22:44:59 INFO - mozversion application_version: 55.0a1 22:44:59 INFO - mozversion platform_buildid: 20170527213642 22:44:59 INFO - mozversion platform_changeset: 6a235092bda7c20cb83651d3404284fe36d04c1b 22:44:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:44:59 INFO - mozversion platform_version: 55.0a1 22:44:59 INFO - using testdate: 1495950299 22:44:59 INFO - actual date: 1495950299 22:44:59 INFO - starting webserver on 'localhost:49234' 22:44:59 INFO - SUITE-START | Running 2 tests 22:44:59 INFO - TEST-START | dromaeo_css 22:44:59 INFO - operating with platform_type : mac_ 22:44:59 INFO - Initialising browser for dromaeo_css test... 22:44:59 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpYeI2aI/profile 22:44:59 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground) 22:45:33 INFO - TEST-INFO | 1653: killed by SIGTERM 22:45:33 INFO - Browser initialized. 22:45:33 INFO - Running cycle 1/1 for dromaeo_css test... 22:45:33 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpYeI2aI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:45:35 INFO - PID 1661 | 2017-05-27 22:45:35.868 plugin-container[1662:8977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 22:45:35 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:45:35 INFO - PID 1661 | 2017-05-27 22:45:35.869 plugin-container[1662:8977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 22:45:35 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:45:35 INFO - PID 1661 | 2017-05-27 22:45:35.869 plugin-container[1662:8977] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:45:36 INFO - PID 1661 | 2017-05-27 22:45:36.246 plugin-container[1663:9020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 22:45:36 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:45:36 INFO - PID 1661 | 2017-05-27 22:45:36.246 plugin-container[1663:9020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 22:45:36 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:45:36 INFO - PID 1661 | 2017-05-27 22:45:36.246 plugin-container[1663:9020] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:45:45 INFO - PID 1661 | RSS: Main: 262848512 22:45:45 INFO - PID 1661 | 22:48:48 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 22:48:49 INFO - PID 1661 | RSS: Main: 268087296 22:48:49 INFO - PID 1661 | 22:51:51 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 22:51:52 INFO - PID 1661 | RSS: Main: 283983872 22:51:52 INFO - PID 1661 | 22:54:54 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 22:54:54 INFO - PID 1661 | RSS: Main: 283893760 22:54:54 INFO - PID 1661 | 22:57:56 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 22:57:57 INFO - PID 1661 | RSS: Main: 287494144 22:57:57 INFO - PID 1661 | 23:01:00 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 23:01:00 INFO - PID 1661 | RSS: Main: 287682560 23:01:00 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 23:04:02 INFO - PID 1661 | RSS: Main: 287535104 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | __start_tp_report 23:04:02 INFO - PID 1661 | _x_x_mozilla_page_load 23:04:02 INFO - PID 1661 | _x_x_mozilla_page_load_details 23:04:02 INFO - PID 1661 | |i|pagename|runs| 23:04:02 INFO - PID 1661 | |0;dojo.html;3590.9461358079634;3594.1913069559337;3621.221437390961;3631.8002509861963;3637.38164512033;4841.612670986326;4905.705657660543;5417.34991800984;6208.751649934008;6263.373662633734;6151.507879369651;6181.845453863656;6182.876342473148;6244.157038799761;6246.344133865947;5040.218766091243;5297.205419187134;5401.675899446026;5437.401885792559;5447.782088716446;5096.566791822699;5553.139263414157;5566.944330556704;5571.665700057989;5608.327000759915;5365.302510673627;5427;5434;5479.506844384025;5511.255980442649;5489.368722596872;5493.972530137324;5493.972530137324;5499.395066542677;5527.613067085305;5400.91898621521;5412.485813847678;5444.455554444564;5479.287692599976;5493.395726470085;6150.8769824603705;6187.5668703190795;6243.2508099027955;6244.781432649836;6278.937210627927;6540.509461790385;6632.436242919358;6735.259121496631;6744.898826517606;6755.729770809162;6807.183138023419;6838.247792742794;6861.382475577172;6862.416694580967;6863.553868998549;6319.146915166486;6347.1114044033875;6351.428371446638;6358.268799088118;6358.586691865014;6732.124823772878;6810.5913645181445;6894.862102757917;6947.096877405905;6967.79096627102;5119.974400127976;6373.362663733589;6426.2931077581425;6446.161998940202;6616;6682.298358672367;6713.060171575983;6792.694328755218;6797.592144471348;6820.863582728318;6693.698783554752;6701.6314102723845;6719.596824190564;6726.192856857208;6759.155105611799;6437.163168788027;6504.154459920274;6578.3092775258865;6703.463722902254;6721.26066132725;5376.973115134399;5394.973025134849;5396.082665946798;5402.972985135128;5449.427810079964;4770.308305295713;4790.233562630032;4819.3012013257885;4864.246041863516;4878.658493905393;4969.906620543475;6130.785422510191;6318.968405158037;6322.589031712924;6326.968365158237;5635.830925072254;5676.659400435987;6730.125083739181;6820.590764554143;6861.348171923659;6283.128636069794;6311.33730958252;6317.905231421532;6335.746570137143;6340.334264902119;5859.53123750099;5991.55063370249;6029.60807547508;6064.545159113084;6070.362611925772;6811.352921472452;6855.108835851407;6908.516403851682;6967;6991.056207412138;6757.121574195323;6818.8636227276165;6864.656767161522;6869.450443964371;6873.20958089806;6954.687039083152;7002.75490357835;7007.159140902921;7012.263712310134;7016.087908571853;6546.18172728409;6553.115329430466;6652.73389064432;6704.363085506869;6760.695768690322;6819.113515242987;6850.177978642595;6869.244383117953;6876.621785801829;6911.481638877205;6030.5778595497895;6110.236220472441;6650.534462587572;6672.599644021374;6677.165354330708;6428.582142160744;6545.37818907203;6550.868982620225;6611.867762644816;6638.701258443285;6369.426751592379;6449.774257900951;6453.354664533509;6485.254195767548;6502.804915852532;5805.925903707827;6370.458511026614;6501;6591.274959754519;6614.470842332698;6405.7117429714845;6423.775167869102;6432.871342573216;6442.033694945796;6445.935540644534 23:04:02 INFO - PID 1661 | |1;ext.html;672.831792051987;790.6679194738209;845.4251109245714;1151.453059796596;1265.4305562496872;39137.825865224055;40234;40276.39585406214;40335.596644033554;40366.59633403665;40846.795766021125;41254;41292.587074129326;41310.76067717973;41333.37999929996;7806.84386312272;8030.116687164407;8304.169583041677;8534.957325213365;8566.143385661446;7160.462965277625;8510.70212542561;8518.148185181462;8520.531370774605;8529.57352132394;8468.280196183332;8543.401961862672;8547.871781923277;8552.5296108714;8568.314534837198;8400.873986890201;8462.87305690412;8503.914960850374;8510.531920744388;8514.787130321729;8237.876431853527;8274.255317021436;8281.047679516812;8336.291415229713;8336.958315208445;37925;38215.61784382176;38226.0443488915;38262.80868595667;38312.80843595792;3112.813231206135;3539.964600353989;3587.712982961357;3590.7127429805555;3608.5128507651393;30468.84765576158;30718.692813071808;30767;30769.230769230726;30871.53692694589;3095.7059079387423;3112.5331200319906;3123.2348074721613;3148.354587309596;3150.070729134911;246684;251953;280044.99999999796;311679;312308;49162.50837491579;49317.02731890756;49347;49469;49520.50479495231;50106.74946625243;50576;50921.490785092414;51324.97350052978;51414.97170056578;14532.491362802251;14685.412583496754;14744.926275368769;14782.778258326134;14796;11714.589989350332;11764.88235117655;11767.705807354714;11862.762744745229;11889.346085965182;11466.48400821965;14201.431942722176;14291.64270893236;14333.713325733426;14399.64000899965;5117.667351622133;6113.969430152821;6135.5398345124295;6162;6181.660008699475;827.7971896885301;856.3620103023287;938.4697645830084;975.5561219645186;1051.5163025008583;25084.372890677878;25102.37244068913;25115.37211569689;25233.243002709947;25513.851876665594;1055.0293729768632;1108.3848464102339;1138.0724709361843;1138.527511082908;1141.8058929981923;11285.830712539155;12845.357732113356;12856.935715321551;12865.678358040937;12934.67663308406;349520;349753.7536936948;350500;350640;351351;55906.720466397404;56175.438245617835;56185.15722264169;56575.71712141413;56721;1456.7377871983144;1485.3909896942203;1512.296781996377;1520.9239538023276;1526.3665578784903;24411.51176976486;24589.13938012161;24611;24635.137770178673;24669.62995555104;38990.6100938987;52192;53402.73298633637;53455.73272133614;53703.73148134234;277564;278500;279170;279191.60404198663;279357;36941.89174324775;37088.443673345464;37190;37231.25537489289;37363.06592335214;44174.89562760957;44547.777261113486;44812;46727.53272467368;46748.065038698354;30087.849560752056;31050.534241985937;31760.36479270448;31804.840975794974;32107.678923211402;26074.565898875742;26289.868550657888;26868.05961791328;31397.372052559276;32478.67521324851;31068.68931310658;38798.80600596979;38914.2217155661;38934.026649333995;38943.610563894;30092.19446833197;31902.202444939063;32082;32486.51270230901;32811.343773123925;19304.13131409118;20737.792622073586;21956;22299.331020069425;24773.132940347 23:04:02 INFO - PID 1661 | |2;jquery.html;366.38996071540885;407.5109868158209;422.66398213420325;433.4430257119602;447.84773177119774;20970.790292097074;21758.129674813008;21779.564408711816;22103.889480552614;23320.766792332113;23292.41768955777;23503.05987760492;23530.35279531671;23597.528049439003;23620.055197792066;5978.073398623221;6205.193324867761;6238.00191969285;6246.312905580406;6257.749690012394;5846.386129456409;6085.361037091108;6141.815745527641;6179.721912513925;6183.103449999748;6044.788432404866;6105.8168254952425;6125.264968203822;6125.632462052269;6128.724207410677;5819.67991760453;6062.878742425127;6088.634681919099;6092.756289748405;6121.602095863755;5420.91868621971;5424.864378390533;5431.592630552725;5435.157550579627;5442.6462279952075;22836.771632283795;27489.587656184973;28140.718592814013;28164.43671126566;28180.295492612644;1902.4102528216158;2131.7335333083365;2208.5251670890834;2210.502636906699;2211.9336419907427;14633.853661463356;17374;18244.817551824577;18246.817531824778;18378.540536486562;1796.8742188046842;2077.7298951136254;2083.656196727538;2124.150339864051;2134.647783115784;140029.8995515068;154131;163258;171463;175096.12451937786;10192.898071019343;12308.323042232763;12653.810192846931;12667.809982850265;12745.68135796594;16484;16645.084520351254;16649.41727039548;16672.83327166713;16699.6660066798;8757.781055473664;8806.691765788295;8981.236594889377;9041.593128309241;9115.589798459085;7328.267173282629;8249.463784853966;8486.83026339482;8531.146885311544;8637.309015278763;7704.92295077042;7970.322522585529;7997.280244777998;8004.319632831158;8009.159038301011;2153.257126291427;2173.3262688566597;2174;2174.0977494339986;2328.9767102329097;861.2421069458836;922.7739203895088;940.4498368454394;941.2470023980788;948.9857652135223;20166.69749953752;20721.27475538349;20743.273985410437;20780.425523425758;20899.164033438494;771.9073711154697;914.1635403605759;975.4878688688353;979.6277414582555;1009.8333774927202;10138.594456221668;10774.461276936121;10880.292780969212;10886.129109671207;10933.890661093446;455089;491700;550775;563641;569222;117565.8243417572;120218.79781202076;128444;129457;132221;1123.4831977290376;1143.5654451308449;1167.3871217610822;1170;1170.3153655111685;17737.556561086076;17863.861213683696;17999.910000450436;18264.36074737378;18605.534861628566;53948.73025634847;54113;55812;55883;56786.7160664194;58952.70523647354;76544.85182722152;77098.6145069271;77482.61258693671;77620.61189694016;8747.16901894319;8793.340499462694;8931.955340223518;9002.50486223264;9011.549422528717;13485.528006519724;13688.726225475235;13803.792943106062;13953.023288370123;14109.929450352682;11694.88305116938;11748.177627565989;11752.23610465317;11880.643580692247;12046.397680115784;10717.571297148339;10784.406857622906;10848.426978088291;10935.234533582572;11115;11611.709707257049;11675.88324116782;11959.701007474883;12138.271703697807;12206.572769953009;8704.825903482022;8832.249258813074;8897.73306800797;8925.866112008196;8955.238804701674;11716;11802.881971180523;11979.700507487383;11997.700057498632;12136.757264854477 23:04:02 INFO - PID 1661 | |3;mootools.html;1279.1749321687512;1411.738828316762;1413.7101894111709;1419.5954153066373;1434;11629.65111046669;11909.344986025764;12040.277583344994;12051.096167787418;12260.632181034562;10010.249231307645;11697.883021169786;11862.82205766912;11914.285142891411;11957.760844783099;2522.079441004034;2552.2088152672636;2567.1785028790796;2569.665943427352;2569.961450578243;2501.8373805702663;2537.175417989161;2559.398541342783;2559.6032614944716;2560.2447278052928;2507.4985002999383;2524.4320027993645;2529.3929456930296;2529.7217306096313;2532.8733563321853;2479.466914613358;2493.227099599121;2504.1235567551394;2504.8747562621797;2513.7611926866916;2152.8062474377225;2307.48081681622;2483.6522886795865;2502.6110508667684;2539.7968162547136;11596;11713.004394626467;11723.706907327385;11729.472173752201;11733.178677492579;6431.517636177306;6645.767398141042;6664.100346453215;6674.799756007327;6681.632510211936;3846.8461261549505;3941.8029098544957;3994.6804255659476;4005.3190957537;4008.4187792769885;1255.127686258051;1259.2822091407902;1262.4823822232931;1264.8608653048157;1285.4215602978697;15118.924405377902;15547.766783498144;16299.266533006043;16444;16531.33874645013;4391.8682439526865;5457.508824205841;5548.250986116904;5596.720163991784;5607.831765047055;9804;11964.222325548812;11986.64040078799;11995.280283183038;12035.518579256906;6358.3641635836975;6488.416042556099;6503.154589903283;6512.73949042033;6515.348465153446;5106.617003724736;5947.732352044169;6158.3841615838055;6169.074638804215;6194.473469755121;4284.807183676742;4343.609075183249;4391.912161756811;4422.049259409242;4425.114977004612;1245.9626211213497;1260.8171815086762;1262.8295180150562;1272.7263638317806;1274.9107562470538;769.288408222392;773.7175930785336;787.7872974297022;789.5499565247868;790.4664351562673;6603.141591593062;6829.965850170717;6906.447484201251;7013.3687968082095;7057;786.4180506425204;902.3818684201343;942.2509105261197;946.7822400847843;947.8436058050482;3559.7864128152396;3882.437046628223;3967.0875698588907;4360.978195109004;4454.398656181439;14753.114813111248;14832.406703731946;14933.029353092232;15141.772873406908;15197.316120774592;14306.642333941734;14830.851691482947;14855.108693478425;14950.327235274439;15025.173615451255;8519.48883067018;8593.613287402082;8605.870911936327;8663.436876603128;8672.26285765717;5744.741486633028;5778.017736984807;6821.93178068213;7061.646917653994;7236.638168091679;12994.115853201421;13339.599812005656;13356.332183390987;13625;13627.182369057888;13601.727965440834;13787.655308616964;13866.376013079234;13902.37439315213;13991.650208744863;9368.906310936803;9402.95298523503;9496.43021418717;9500.002499737428;9568.760780980252;10626;10646.20153488507;10746.140308775435;10756.408397538211;10774.515146818256;8557.315414766928;9247.029061948399;9282.535873206447;9282.767930801783;9413.388129771543;9009.189172974464;9127.726168214964;9136.817263654557;9159.725208243763;9227.35408521397;8169.714060007871;8181.836363272582;8231.547264900488;8289.502629842229;8294.253517183482;5984.162217289496;6037.849053773691;6148.1699970503405;6179.3202747698615;6229.4393504584805;4939.950600493949;5073.847784566469;6038.094285857156;6038.5772995889865;6233.906491402723 23:04:02 INFO - PID 1661 | |4;prototype.html;328.3711692109609;393.65555139253155;395.46019683132533;400.31827774180965;405.456688038029;5300.390455097665;5510.559155267579;6059.848628760538;6468.223813142417;6571.2771595124495;6048.788292409767;6237.313895471494;6316;6354.332795056522;6355.745770169199;3695.5011073505134;4696.976515117437;4708.634495996157;4727.196376615975;4736.9578692687555;3876.9224615507674;4724;4748.264019077049;4753.47711751707;4767.856964291077;4309.698321117523;4560.5862182723295;4675.859724208279;4703.6472264580125;4728.935989402391;3802.258559580883;4261.850835220753;4700.600448961843;4701.48283688794;4748.7890587899865;3639.253952939641;3824.043989002749;4155.459790227282;4167.52073511547;4181.560936101696;4995.450500444948;5085.949140508585;6082.270127584674;6207.751689932397;6249.375062493714;4910.845951201465;5068.949310506885;5629.493345598875;5636.633618814764;5637.379888212294;6203.596766210182;6620.966895165541;6671.432928201109;6789.1301957884025;6807.353301436356;4319.222539942809;4671.696339737906;4730.604471680863;4761.547652972962;4781.689190202661;6516.380943810331;6851.623160726158;7043.436525077981;7149.213586505481;7157.174920395318;4484.798184081659;4678.643193473922;4842.927356089662;4935.481774413634;4964.975175124101;4584.816607335669;4803.495632958559;4872.4883887192045;5057.861981054233;5072.923906141411;5197.454267301954;5670.943290567042;5686.9715651421475;5695.430456954271;5699.2590963174525;6364.459020983176;6619.635920024445;6792.400027985077;6796.8300792479595;6827.317268273133;1474.9336279867432;1483.5030264861177;1604.163233098096;1721.1480317242795;1724.1637805664243;944.3436811416137;986.9950650246702;1023.5649848814224;1030.7165529479303;1040.5016775842762;6115.908261376083;6323.336049714806;6343.365663433619;6354.745810167541;6396.392342727433;6114.7248373822395;6965.233824279325;6980.336867997532;7006.75476358325;7038.190608080034;4628.930566041512;6293.905591416133;6296.212973378328;6325.304216536178;6333.968330158412;3234.0136258441403;3608.8556457741397;3665.1020499977676;3673.228621989412;3710.7216958727663;6898.379145876795;7077.787666370017;7234.023406840114;7243.927560724431;7259.564426134449;5402.081646120169;6432.614043157426;7137.96431017852;7206.783796486009;7255.201927787939;6313.905291420632;7073.469489788184;7222.530535515175;7226.385757210591;7308.6345682715655;6035.517158627387;6806.319368063154;6923.9307606923285;6975.755848545276;7117.501774875709;5749.91375129365;6449.968005119158;6568.700954665829;6901.309869013058;6911.274316196928;6673.799786006428;7148.678309475982;7156.711791877512;7166.928330716626;7226.855462890818;3553.3959226930965;4429.136318417877;4452.532484089124;4470.100207411902;4475.9552404475535;6086.817395478142;6197.194364732556;6906.687729331411;6960.721571137098;6998.510104292651;6761.188657360952;6768.323167683192;6771.3905748482875;6771.476417805954;6775.322467753185;5868.2077919480325;6551.901721474277;6764.763233286812;6782.728690852311;6829.634073185283;5798.520887467485;6071.848203794941;6578.967105164444;6604.471642268511;6746.696398661974;6429.03564465334;6479.028145778171;6553.705083271359;6670.232923213698;6708.22855371619;5161.354830646169;6087.512998959984;6513.641749703812;6537.967310163609;6579.901301480386 23:04:02 INFO - PID 1661 | |5;yui.html;781.5662307419382;813.3858936502946;826.6775957376628;858.2404571953819;865.5196366016862;16829.579260518494;17249.396271130496;17286.48140555784;19584.118714657838;20163.899180504115;18626.813731862676;18945;18996.24015039397;19054.284414539172;19119.330823421184;996.1781530237565;1003.9447830369327;1015.1219195396003;1017.6489111256604;1020.8570800087994;1005.4520286443906;1029.5469993203005;1029.6910926721991;1030.386919782728;1033.2715435617908;1066.4934156275785;1083.6911480228136;1084.701707030569;1086.6196831109128;1087.5541028178409;1052.237128082142;1065.9520321585546;1075.6557901471492;1077.6228320087985;1081.5727787523924;795.6220795122329;802.3380710913519;802.5104686141449;802.7511471443871;803.0724513187278;14573.927130364385;17589.472315830546;17993.01038442885;18015;18061.548461288574;17712.380066697602;17734.02462864542;17753.201105950262;17799.644007119914;18122.546936326566;14474.42102315906;14575.052621579563;14582.416703331854;14782.926085369612;14809.70380592393;3438.862445502177;3476.3047390521997;3501.3522498337634;3503.7722548034294;3505.491703702974;138423.99999999898;161561;161833.1908340451;163290;164678;13833.515826946008;14059.43762249519;14177.716445671234;14179.716405671828;14183;26318.6052209217;26332.078377257087;26442.206733798015;27162.185134445997;27346.726532734814;7234.312740289664;7273.309035641606;7293.197748247689;7330.340269375782;7350.852982940311;3932.508436445444;3948.0722030323427;3959.940600890989;4033.4554835097474;4037.333839916381;4418.138462999685;4507.729536227836;4514.052049069666;4517.299818528133;4519.322101684774;916.6929078758691;951.3816019587324;951.7668171298076;960.3901522533102;962.4033099478228;567.6480582039162;642.5534253693731;655.063259538864;678.7692184657241;681.3186813186813;11500.309981401142;12343.13598048128;12529.561465348668;12808.871911280954;12939.870601294055;614.4899733221415;634.4828964394004;642.4539141729585;660.2901880478505;662.4468568745056;5484.177373394023;5816.563757718188;5821.563382746311;5829.329627092854;5929.555283353766;125138;125882;126052.36973815072;126365;130184;106085.9391406076;108310;108933;122474;123258.38370808089;92836.21491355405;95544.52227738817;95972;96438.0356196443;96727.51636241915;11049.226554141133;11841.881581184078;12761.744765104831;12895.03287253441;13079.607611771662;41617;41659.37510937399;41675.16649667051;41817.58182418137;41862.74411767652;56523.151503035726;57315.71342143263;57538.424615753305;57681;57739.711301443225;16468.17659117063;16666.4166754163;17055.147242638068;17108;17278.740818887476;17949.820501794817;18786.71819922675;18896;18916.716249256;18937.90531047336;17089.401870934456;17241.91379043097;17937.910310448366;18070.186841591898;18271;12304.384780760747;15995.920020400288;16028.358865645243;16125.596860078123;16195.676086478441;16595.91702041482;16739.91190572609;17009.914950425664;17155.970641761534;17389.304427822746;10994.780104398027;11863.822042669539;11965.880341196476;12212.267263964191;12629.2422454653;13236.293837725452;13921.860781392461;13939;13944.930275348559;13966.231857247543 23:04:02 INFO - PID 1661 | __end_tp_report 23:04:02 INFO - PID 1661 | __start_cc_report 23:04:02 INFO - PID 1661 | _x_x_mozilla_cycle_collect,453 23:04:02 INFO - PID 1661 | __end_cc_report 23:04:02 INFO - PID 1661 | __startTimestamp1495951442747__endTimestamp 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | ------- Summary: start ------- 23:04:02 INFO - PID 1661 | Number of tests: 6 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | [#0] dojo.html Cycles:165 Average:6156.75 Median:6370.46 stddev:752.21 (11.8%) stddev-sans-first:727.09 23:04:02 INFO - PID 1661 | Values: 3590.9 3594.2 3621.2 3631.8 3637.4 4841.6 4905.7 5417.3 6208.8 6263.4 6151.5 6181.8 6182.9 6244.2 6246.3 5040.2 5297.2 5401.7 5437.4 5447.8 5096.6 5553.1 5566.9 5571.7 5608.3 5365.3 5427.0 5434.0 5479.5 5511.3 5489.4 5494.0 5494.0 5499.4 5527.6 5400.9 5412.5 5444.5 5479.3 5493.4 6150.9 6187.6 6243.3 6244.8 6278.9 6540.5 6632.4 6735.3 6744.9 6755.7 6807.2 6838.2 6861.4 6862.4 6863.6 6319.1 6347.1 6351.4 6358.3 6358.6 6732.1 6810.6 6894.9 6947.1 6967.8 5120.0 6373.4 6426.3 6446.2 6616.0 6682.3 6713.1 6792.7 6797.6 6820.9 6693.7 6701.6 6719.6 6726.2 6759.2 6437.2 6504.2 6578.3 6703.5 6721.3 5377.0 5395.0 5396.1 5403.0 5449.4 4770.3 4790.2 4819.3 4864.2 4878.7 4969.9 6130.8 6319.0 6322.6 6327.0 5635.8 5676.7 6730.1 6820.6 6861.3 6283.1 6311.3 6317.9 6335.7 6340.3 5859.5 5991.6 6029.6 6064.5 6070.4 6811.4 6855.1 6908.5 6967.0 6991.1 6757.1 6818.9 6864.7 6869.5 6873.2 6954.7 7002.8 7007.2 7012.3 7016.1 6546.2 6553.1 6652.7 6704.4 6760.7 6819.1 6850.2 6869.2 6876.6 6911.5 6030.6 6110.2 6650.5 6672.6 6677.2 6428.6 6545.4 6550.9 6611.9 6638.7 6369.4 6449.8 6453.4 6485.3 6502.8 5805.9 6370.5 6501.0 6591.3 6614.5 6405.7 6423.8 6432.9 6442.0 6445.9 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | [#1] ext.html Cycles:180 Average:46159.49 Median:3563.84 stddev:80191.05 (2250.1%) stddev-sans-first:80343.28 23:04:02 INFO - PID 1661 | Values: 672.8 790.7 845.4 1151.5 1265.4 39137.8 40234.0 40276.4 40335.6 40366.6 40846.8 41254.0 41292.6 41310.8 41333.4 7806.8 8030.1 8304.2 8535.0 8566.1 7160.5 8510.7 8518.1 8520.5 8529.6 8468.3 8543.4 8547.9 8552.5 8568.3 8400.9 8462.9 8503.9 8510.5 8514.8 8237.9 8274.3 8281.0 8336.3 8337.0 37925.0 38215.6 38226.0 38262.8 38312.8 3112.8 3540.0 3587.7 3590.7 3608.5 30468.8 30718.7 30767.0 30769.2 30871.5 3095.7 3112.5 3123.2 3148.4 3150.1 246684.0 251953.0 280045.0 311679.0 312308.0 49162.5 49317.0 49347.0 49469.0 49520.5 50106.7 50576.0 50921.5 51325.0 51415.0 14532.5 14685.4 14744.9 14782.8 14796.0 11714.6 11764.9 11767.7 11862.8 11889.3 11466.5 14201.4 14291.6 14333.7 14399.6 5117.7 6114.0 6135.5 6162.0 6181.7 827.8 856.4 938.5 975.6 1051.5 25084.4 25102.4 25115.4 25233.2 25513.9 1055.0 1108.4 1138.1 1138.5 1141.8 11285.8 12845.4 12856.9 12865.7 12934.7 349520.0 349753.8 350500.0 350640.0 351351.0 55906.7 56175.4 56185.2 56575.7 56721.0 1456.7 1485.4 1512.3 1520.9 1526.4 24411.5 24589.1 24611.0 24635.1 24669.6 38990.6 52192.0 53402.7 53455.7 53703.7 277564.0 278500.0 279170.0 279191.6 279357.0 36941.9 37088.4 37190.0 37231.3 37363.1 44174.9 44547.8 44812.0 46727.5 46748.1 30087.8 31050.5 31760.4 31804.8 32107.7 26074.6 26289.9 26868.1 31397.4 32478.7 31068.7 38798.8 38914.2 38934.0 38943.6 30092.2 31902.2 32082.0 32486.5 32811.3 19304.1 20737.8 21956.0 22299.3 24773.1 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | [#2] jquery.html Cycles:180 Average:34784.92 Median:12810.70 stddev:90038.77 (702.8%) stddev-sans-first:90254.27 23:04:02 INFO - PID 1661 | Values: 366.4 407.5 422.7 433.4 447.8 20970.8 21758.1 21779.6 22103.9 23320.8 23292.4 23503.1 23530.4 23597.5 23620.1 5978.1 6205.2 6238.0 6246.3 6257.7 5846.4 6085.4 6141.8 6179.7 6183.1 6044.8 6105.8 6125.3 6125.6 6128.7 5819.7 6062.9 6088.6 6092.8 6121.6 5420.9 5424.9 5431.6 5435.2 5442.6 22836.8 27489.6 28140.7 28164.4 28180.3 1902.4 2131.7 2208.5 2210.5 2211.9 14633.9 17374.0 18244.8 18246.8 18378.5 1796.9 2077.7 2083.7 2124.2 2134.6 140029.9 154131.0 163258.0 171463.0 175096.1 10192.9 12308.3 12653.8 12667.8 12745.7 16484.0 16645.1 16649.4 16672.8 16699.7 8757.8 8806.7 8981.2 9041.6 9115.6 7328.3 8249.5 8486.8 8531.1 8637.3 7704.9 7970.3 7997.3 8004.3 8009.2 2153.3 2173.3 2174.0 2174.1 2329.0 861.2 922.8 940.4 941.2 949.0 20166.7 20721.3 20743.3 20780.4 20899.2 771.9 914.2 975.5 979.6 1009.8 10138.6 10774.5 10880.3 10886.1 10933.9 455089.0 491700.0 550775.0 563641.0 569222.0 117565.8 120218.8 128444.0 129457.0 132221.0 1123.5 1143.6 1167.4 1170.0 1170.3 17737.6 17863.9 17999.9 18264.4 18605.5 53948.7 54113.0 55812.0 55883.0 56786.7 58952.7 76544.9 77098.6 77482.6 77620.6 8747.2 8793.3 8932.0 9002.5 9011.5 13485.5 13688.7 13803.8 13953.0 14109.9 11694.9 11748.2 11752.2 11880.6 12046.4 10717.6 10784.4 10848.4 10935.2 11115.0 11611.7 11675.9 11959.7 12138.3 12206.6 8704.8 8832.2 8897.7 8925.9 8955.2 11716.0 11802.9 11979.7 11997.7 12136.8 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | [#3] mootools.html Cycles:180 Average:7084.28 Median:3864.64 stddev:4492.98 (116.3%) stddev-sans-first:4484.41 23:04:02 INFO - PID 1661 | Values: 1279.2 1411.7 1413.7 1419.6 1434.0 11629.7 11909.3 12040.3 12051.1 12260.6 10010.2 11697.9 11862.8 11914.3 11957.8 2522.1 2552.2 2567.2 2569.7 2570.0 2501.8 2537.2 2559.4 2559.6 2560.2 2507.5 2524.4 2529.4 2529.7 2532.9 2479.5 2493.2 2504.1 2504.9 2513.8 2152.8 2307.5 2483.7 2502.6 2539.8 11596.0 11713.0 11723.7 11729.5 11733.2 6431.5 6645.8 6664.1 6674.8 6681.6 3846.8 3941.8 3994.7 4005.3 4008.4 1255.1 1259.3 1262.5 1264.9 1285.4 15118.9 15547.8 16299.3 16444.0 16531.3 4391.9 5457.5 5548.3 5596.7 5607.8 9804.0 11964.2 11986.6 11995.3 12035.5 6358.4 6488.4 6503.2 6512.7 6515.3 5106.6 5947.7 6158.4 6169.1 6194.5 4284.8 4343.6 4391.9 4422.0 4425.1 1246.0 1260.8 1262.8 1272.7 1274.9 769.3 773.7 787.8 789.5 790.5 6603.1 6830.0 6906.4 7013.4 7057.0 786.4 902.4 942.3 946.8 947.8 3559.8 3882.4 3967.1 4361.0 4454.4 14753.1 14832.4 14933.0 15141.8 15197.3 14306.6 14830.9 14855.1 14950.3 15025.2 8519.5 8593.6 8605.9 8663.4 8672.3 5744.7 5778.0 6821.9 7061.6 7236.6 12994.1 13339.6 13356.3 13625.0 13627.2 13601.7 13787.7 13866.4 13902.4 13991.7 9368.9 9403.0 9496.4 9500.0 9568.8 10626.0 10646.2 10746.1 10756.4 10774.5 8557.3 9247.0 9282.5 9282.8 9413.4 9009.2 9127.7 9136.8 9159.7 9227.4 8169.7 8181.8 8231.5 8289.5 8294.3 5984.2 6037.8 6148.2 6179.3 6229.4 4940.0 5073.8 6038.1 6038.6 6233.9 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | [#4] prototype.html Cycles:180 Average:5440.10 Median:6115.32 stddev:1707.71 (27.9%) stddev-sans-first:1668.84 23:04:02 INFO - PID 1661 | Values: 328.4 393.7 395.5 400.3 405.5 5300.4 5510.6 6059.8 6468.2 6571.3 6048.8 6237.3 6316.0 6354.3 6355.7 3695.5 4697.0 4708.6 4727.2 4737.0 3876.9 4724.0 4748.3 4753.5 4767.9 4309.7 4560.6 4675.9 4703.6 4728.9 3802.3 4261.9 4700.6 4701.5 4748.8 3639.3 3824.0 4155.5 4167.5 4181.6 4995.5 5085.9 6082.3 6207.8 6249.4 4910.8 5068.9 5629.5 5636.6 5637.4 6203.6 6621.0 6671.4 6789.1 6807.4 4319.2 4671.7 4730.6 4761.5 4781.7 6516.4 6851.6 7043.4 7149.2 7157.2 4484.8 4678.6 4842.9 4935.5 4965.0 4584.8 4803.5 4872.5 5057.9 5072.9 5197.5 5670.9 5687.0 5695.4 5699.3 6364.5 6619.6 6792.4 6796.8 6827.3 1474.9 1483.5 1604.2 1721.1 1724.2 944.3 987.0 1023.6 1030.7 1040.5 6115.9 6323.3 6343.4 6354.7 6396.4 6114.7 6965.2 6980.3 7006.8 7038.2 4628.9 6293.9 6296.2 6325.3 6334.0 3234.0 3608.9 3665.1 3673.2 3710.7 6898.4 7077.8 7234.0 7243.9 7259.6 5402.1 6432.6 7138.0 7206.8 7255.2 6313.9 7073.5 7222.5 7226.4 7308.6 6035.5 6806.3 6923.9 6975.8 7117.5 5749.9 6450.0 6568.7 6901.3 6911.3 6673.8 7148.7 7156.7 7166.9 7226.9 3553.4 4429.1 4452.5 4470.1 4476.0 6086.8 6197.2 6906.7 6960.7 6998.5 6761.2 6768.3 6771.4 6771.5 6775.3 5868.2 6551.9 6764.8 6782.7 6829.6 5798.5 6071.8 6579.0 6604.5 6746.7 6429.0 6479.0 6553.7 6670.2 6708.2 5161.4 6087.5 6513.6 6538.0 6579.9 23:04:02 INFO - PID 1661 | 23:04:02 INFO - PID 1661 | [#5] yui.html Cycles:180 Average:24722.11 Median:17943.87 stddev:37730.88 (210.3%) stddev-sans-first:37793.91 23:04:02 INFO - PID 1661 | Values: 781.6 813.4 826.7 858.2 865.5 16829.6 17249.4 17286.5 19584.1 20163.9 18626.8 18945.0 18996.2 19054.3 19119.3 996.2 1003.9 1015.1 1017.6 1020.9 1005.5 1029.5 1029.7 1030.4 1033.3 1066.5 1083.7 1084.7 1086.6 1087.6 1052.2 1066.0 1075.7 1077.6 1081.6 795.6 802.3 802.5 802.8 803.1 14573.9 17589.5 17993.0 18015.0 18061.5 17712.4 17734.0 17753.2 17799.6 18122.5 14474.4 14575.1 14582.4 14782.9 14809.7 3438.9 3476.3 3501.4 3503.8 3505.5 138424.0 161561.0 161833.2 163290.0 164678.0 13833.5 14059.4 14177.7 14179.7 14183.0 26318.6 26332.1 26442.2 27162.2 27346.7 7234.3 7273.3 7293.2 7330.3 7350.9 3932.5 3948.1 3959.9 4033.5 4037.3 4418.1 4507.7 4514.1 4517.3 4519.3 916.7 951.4 951.8 960.4 962.4 567.6 642.6 655.1 678.8 681.3 11500.3 12343.1 12529.6 12808.9 12939.9 614.5 634.5 642.5 660.3 662.4 5484.2 5816.6 5821.6 5829.3 5929.6 125138.0 125882.0 126052.4 126365.0 130184.0 106085.9 108310.0 108933.0 122474.0 123258.4 92836.2 95544.5 95972.0 96438.0 96727.5 11049.2 11841.9 12761.7 12895.0 13079.6 41617.0 41659.4 41675.2 41817.6 41862.7 56523.2 57315.7 57538.4 57681.0 57739.7 16468.2 16666.4 17055.1 17108.0 17278.7 17949.8 18786.7 18896.0 18916.7 18937.9 17089.4 17241.9 17937.9 18070.2 18271.0 12304.4 15995.9 16028.4 16125.6 16195.7 16595.9 16739.9 17009.9 17156.0 17389.3 10994.8 11863.8 11965.9 12212.3 12629.2 13236.3 13921.9 13939.0 13944.9 13966.2 23:04:02 INFO - PID 1661 | -------- Summary: end -------- 23:04:02 INFO - PID 1661 | 23:04:03 INFO - TEST-INFO | 1661: exit 0 23:04:03 INFO - TEST-OK | dromaeo_css | took 1143764ms 23:04:03 INFO - TEST-START | kraken 23:04:03 INFO - operating with platform_type : mac_ 23:04:03 INFO - Initialising browser for kraken test... 23:04:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpMtrdgd/profile 23:04:03 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49234/getInfo.html -foreground) 23:04:37 INFO - TEST-INFO | 1669: killed by SIGTERM 23:04:37 INFO - Browser initialized. 23:04:37 INFO - Running cycle 1/1 for kraken test... 23:04:37 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpMtrdgd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:04:39 INFO - PID 1673 | 2017-05-27 23:04:39.459 plugin-container[1674:11472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 23:04:39 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:04:39 INFO - PID 1673 | 2017-05-27 23:04:39.459 plugin-container[1674:11472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 23:04:39 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:04:39 INFO - PID 1673 | 2017-05-27 23:04:39.460 plugin-container[1674:11472] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:04:40 INFO - PID 1673 | 2017-05-27 23:04:40.064 plugin-container[1675:11513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 23:04:40 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:04:40 INFO - PID 1673 | 2017-05-27 23:04:40.064 plugin-container[1675:11513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 23:04:40 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:04:40 INFO - PID 1673 | 2017-05-27 23:04:40.064 plugin-container[1675:11513] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:04:49 INFO - PID 1673 | RSS: Main: 262918144 23:04:49 INFO - PID 1673 | 23:04:52 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 23:04:53 INFO - PID 1673 | RSS: Main: 272396288 23:04:53 INFO - PID 1673 | 23:04:55 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 23:04:56 INFO - PID 1673 | RSS: Main: 280358912 23:04:56 INFO - PID 1673 | 23:04:59 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 23:05:00 INFO - PID 1673 | RSS: Main: 282431488 23:05:00 INFO - PID 1673 | 23:05:02 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 23:05:02 INFO - PID 1673 | RSS: Main: 284340224 23:05:02 INFO - PID 1673 | 23:05:04 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:05 INFO - PID 1673 | RSS: Main: 285478912 23:05:05 INFO - PID 1673 | 23:05:08 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:08 INFO - PID 1673 | RSS: Main: 289689600 23:05:08 INFO - PID 1673 | 23:05:11 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:12 INFO - PID 1673 | RSS: Main: 292380672 23:05:12 INFO - PID 1673 | 23:05:15 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 23:05:16 INFO - PID 1673 | RSS: Main: 291704832 23:05:16 INFO - PID 1673 | 23:05:17 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:18 INFO - PID 1673 | RSS: Main: 291184640 23:05:18 INFO - PID 1673 | 23:05:20 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:21 INFO - PID 1673 | RSS: Main: 290959360 23:05:21 INFO - PID 1673 | 23:05:23 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:24 INFO - PID 1673 | RSS: Main: 290979840 23:05:24 INFO - PID 1673 | 23:05:26 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:05:27 INFO - PID 1673 | RSS: Main: 290852864 23:05:27 INFO - PID 1673 | 23:05:30 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:05:31 INFO - PID 1673 | RSS: Main: 290852864 23:05:31 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 23:05:33 INFO - PID 1673 | RSS: Main: 292683776 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | __start_tp_report 23:05:33 INFO - PID 1673 | _x_x_mozilla_page_load 23:05:33 INFO - PID 1673 | _x_x_mozilla_page_load_details 23:05:33 INFO - PID 1673 | |i|pagename|runs| 23:05:33 INFO - PID 1673 | |0;ai-astar;102;115;113;114;113;114;121;167;168;114 23:05:33 INFO - PID 1673 | |1;audio-beat-detection;140;113;111;146;121;145;109;106;137;109 23:05:33 INFO - PID 1673 | |2;audio-dft;198;204;200;208;236;283;210;201;200;197 23:05:33 INFO - PID 1673 | |3;audio-fft;76;60;60;82;63;61;59;60;81;59 23:05:33 INFO - PID 1673 | |4;audio-oscillator;90;70;83;72;69;69;91;102;102;123 23:05:33 INFO - PID 1673 | |5;imaging-gaussian-blur;89;89;90;89;91;96;90;89;90;89 23:05:33 INFO - PID 1673 | |6;imaging-darkroom;96;96;96;96;97;96;96;150;95;96 23:05:33 INFO - PID 1673 | |7;imaging-desaturate;91;95;93;145;140;81;142;145;91;89 23:05:33 INFO - PID 1673 | |8;json-parse-financial;52;51;54;52;50;52;52;52;51;59 23:05:33 INFO - PID 1673 | |9;json-stringify-tinderbox;59;62;60;64;71;64;70;78;49;57 23:05:33 INFO - PID 1673 | |10;stanford-crypto-aes;85;90;88;88;88;89;88;88;120;88 23:05:33 INFO - PID 1673 | |11;stanford-crypto-ccm;136;140;138;128;133;144;151;160;158;159 23:05:33 INFO - PID 1673 | |12;stanford-crypto-pbkdf2;202;241;186;187;183;188;186;189;221;186 23:05:33 INFO - PID 1673 | |13;stanford-crypto-sha256-iterative;67;68;69;68;68;67;68;69;70;66 23:05:33 INFO - PID 1673 | __end_tp_report 23:05:33 INFO - PID 1673 | __start_cc_report 23:05:33 INFO - PID 1673 | _x_x_mozilla_cycle_collect,1188 23:05:33 INFO - PID 1673 | __end_cc_report 23:05:33 INFO - PID 1673 | __startTimestamp1495951533318__endTimestamp 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | ------- Summary: start ------- 23:05:33 INFO - PID 1673 | Number of tests: 14 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#0] ai-astar Cycles:10 Average:124.10 Median:114.50 stddev:23.34 (20.4%) stddev-sans-first:23.34 23:05:33 INFO - PID 1673 | Values: 102.0 115.0 113.0 114.0 113.0 114.0 121.0 167.0 168.0 114.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:129.00 stddev:16.40 (12.7%) stddev-sans-first:16.31 23:05:33 INFO - PID 1673 | Values: 140.0 113.0 111.0 146.0 121.0 145.0 109.0 106.0 137.0 109.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#2] audio-dft Cycles:10 Average:213.70 Median:206.00 stddev:26.88 (13.0%) stddev-sans-first:27.90 23:05:33 INFO - PID 1673 | Values: 198.0 204.0 200.0 208.0 236.0 283.0 210.0 201.0 200.0 197.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#3] audio-fft Cycles:10 Average:66.10 Median:62.00 stddev:9.55 (15.4%) stddev-sans-first:9.43 23:05:33 INFO - PID 1673 | Values: 76.0 60.0 60.0 82.0 63.0 61.0 59.0 60.0 81.0 59.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:71.00 stddev:18.10 (25.5%) stddev-sans-first:19.17 23:05:33 INFO - PID 1673 | Values: 90.0 70.0 83.0 72.0 69.0 69.0 91.0 102.0 102.0 123.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:90.00 stddev:2.15 (2.4%) stddev-sans-first:2.24 23:05:33 INFO - PID 1673 | Values: 89.0 89.0 90.0 89.0 91.0 96.0 90.0 89.0 90.0 89.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:96.00 stddev:17.08 (17.8%) stddev-sans-first:18.01 23:05:33 INFO - PID 1673 | Values: 96.0 96.0 96.0 96.0 97.0 96.0 96.0 150.0 95.0 96.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#7] imaging-desaturate Cycles:10 Average:111.20 Median:90.00 stddev:27.64 (30.7%) stddev-sans-first:28.34 23:05:33 INFO - PID 1673 | Values: 91.0 95.0 93.0 145.0 140.0 81.0 142.0 145.0 91.0 89.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.00 stddev:2.51 (4.8%) stddev-sans-first:2.65 23:05:33 INFO - PID 1673 | Values: 52.0 51.0 54.0 52.0 50.0 52.0 52.0 52.0 51.0 59.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:63.40 Median:64.00 stddev:8.14 (12.7%) stddev-sans-first:8.48 23:05:33 INFO - PID 1673 | Values: 59.0 62.0 60.0 64.0 71.0 64.0 70.0 78.0 49.0 57.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:88.00 stddev:10.20 (11.6%) stddev-sans-first:10.56 23:05:33 INFO - PID 1673 | Values: 85.0 90.0 88.0 88.0 88.0 89.0 88.0 88.0 120.0 88.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:147.50 stddev:11.61 (7.9%) stddev-sans-first:11.88 23:05:33 INFO - PID 1673 | Values: 136.0 140.0 138.0 128.0 133.0 144.0 151.0 160.0 158.0 159.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.90 Median:188.50 stddev:19.27 (10.2%) stddev-sans-first:20.35 23:05:33 INFO - PID 1673 | Values: 202.0 241.0 186.0 187.0 183.0 188.0 186.0 189.0 221.0 186.0 23:05:33 INFO - PID 1673 | 23:05:33 INFO - PID 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:1.15 (1.7%) stddev-sans-first:1.17 23:05:33 INFO - PID 1673 | Values: 67.0 68.0 69.0 68.0 68.0 67.0 68.0 69.0 70.0 66.0 23:05:33 INFO - PID 1673 | -------- Summary: end -------- 23:05:33 INFO - PID 1673 | 23:05:34 INFO - TEST-INFO | 1673: exit 0 23:05:34 INFO - TEST-OK | kraken | took 90765ms 23:05:34 INFO - SUITE-END | took 1234s 23:05:34 INFO - Completed test suite (00:20:34) 23:05:34 INFO - javascript benchmark 23:05:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7980.374163818906, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3590.9461358079634, 3594.1913069559337, 3621.221437390961, 3631.8002509861963, 3637.38164512033, 4841.612670986326, 4905.705657660543, 5417.34991800984, 6208.751649934008, 6263.373662633734, 6151.507879369651, 6181.845453863656, 6182.876342473148, 6244.157038799761, 6246.344133865947, 5040.218766091243, 5297.205419187134, 5401.675899446026, 5437.401885792559, 5447.782088716446, 5096.566791822699, 5553.139263414157, 5566.944330556704, 5571.665700057989, 5608.327000759915, 5365.302510673627, 5427.0, 5434.0, 5479.506844384025, 5511.255980442649, 5489.368722596872, 5493.972530137324, 5493.972530137324, 5499.395066542677, 5527.613067085305, 5400.91898621521, 5412.485813847678, 5444.455554444564, 5479.287692599976, 5493.395726470085, 6150.8769824603705, 6187.5668703190795, 6243.2508099027955, 6244.781432649836, 6278.937210627927, 6540.509461790385, 6632.436242919358, 6735.259121496631, 6744.898826517606, 6755.729770809162, 6807.183138023419, 6838.247792742794, 6861.382475577172, 6862.416694580967, 6863.553868998549, 6319.146915166486, 6347.1114044033875, 6351.428371446638, 6358.268799088118, 6358.586691865014, 6732.124823772878, 6810.5913645181445, 6894.862102757917, 6947.096877405905, 6967.79096627102, 5119.974400127976, 6373.362663733589, 6426.2931077581425, 6446.161998940202, 6616.0, 6682.298358672367, 6713.060171575983, 6792.694328755218, 6797.592144471348, 6820.863582728318, 6693.698783554752, 6701.6314102723845, 6719.596824190564, 6726.192856857208, 6759.155105611799, 6437.163168788027, 6504.154459920274, 6578.3092775258865, 6703.463722902254, 6721.26066132725, 5376.973115134399, 5394.973025134849, 5396.082665946798, 5402.972985135128, 5449.427810079964, 4770.308305295713, 4790.233562630032, 4819.3012013257885, 4864.246041863516, 4878.658493905393, 4969.906620543475, 6130.785422510191, 6318.968405158037, 6322.589031712924, 6326.968365158237, 5635.830925072254, 5676.659400435987, 6730.125083739181, 6820.590764554143, 6861.348171923659, 6283.128636069794, 6311.33730958252, 6317.905231421532, 6335.746570137143, 6340.334264902119, 5859.53123750099, 5991.55063370249, 6029.60807547508, 6064.545159113084, 6070.362611925772, 6811.352921472452, 6855.108835851407, 6908.516403851682, 6967.0, 6991.056207412138, 6757.121574195323, 6818.8636227276165, 6864.656767161522, 6869.450443964371, 6873.20958089806, 6954.687039083152, 7002.75490357835, 7007.159140902921, 7012.263712310134, 7016.087908571853, 6546.18172728409, 6553.115329430466, 6652.73389064432, 6704.363085506869, 6760.695768690322, 6819.113515242987, 6850.177978642595, 6869.244383117953, 6876.621785801829, 6911.481638877205, 6030.5778595497895, 6110.236220472441, 6650.534462587572, 6672.599644021374, 6677.165354330708, 6428.582142160744, 6545.37818907203, 6550.868982620225, 6611.867762644816, 6638.701258443285, 6369.426751592379, 6449.774257900951, 6453.354664533509, 6485.254195767548, 6502.804915852532, 5805.925903707827, 6370.458511026614, 6501.0, 6591.274959754519, 6614.470842332698, 6405.7117429714845, 6423.775167869102, 6432.871342573216, 6442.033694945796, 6445.935540644534], "value": 6108.032186982188, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.831792051987, 790.6679194738209, 845.4251109245714, 1151.453059796596, 1265.4305562496872, 39137.825865224055, 40234.0, 40276.39585406214, 40335.596644033554, 40366.59633403665, 40846.795766021125, 41254.0, 41292.587074129326, 41310.76067717973, 41333.37999929996, 7806.84386312272, 8030.116687164407, 8304.169583041677, 8534.957325213365, 8566.143385661446, 7160.462965277625, 8510.70212542561, 8518.148185181462, 8520.531370774605, 8529.57352132394, 8468.280196183332, 8543.401961862672, 8547.871781923277, 8552.5296108714, 8568.314534837198, 8400.873986890201, 8462.87305690412, 8503.914960850374, 8510.531920744388, 8514.787130321729, 8237.876431853527, 8274.255317021436, 8281.047679516812, 8336.291415229713, 8336.958315208445, 37925.0, 38215.61784382176, 38226.0443488915, 38262.80868595667, 38312.80843595792, 3112.813231206135, 3539.964600353989, 3587.712982961357, 3590.7127429805555, 3608.5128507651393, 30468.84765576158, 30718.692813071808, 30767.0, 30769.230769230726, 30871.53692694589, 3095.7059079387423, 3112.5331200319906, 3123.2348074721613, 3148.354587309596, 3150.070729134911, 246684.0, 251953.0, 280044.99999999796, 311679.0, 312308.0, 49162.50837491579, 49317.02731890756, 49347.0, 49469.0, 49520.50479495231, 50106.74946625243, 50576.0, 50921.490785092414, 51324.97350052978, 51414.97170056578, 14532.491362802251, 14685.412583496754, 14744.926275368769, 14782.778258326134, 14796.0, 11714.589989350332, 11764.88235117655, 11767.705807354714, 11862.762744745229, 11889.346085965182, 11466.48400821965, 14201.431942722176, 14291.64270893236, 14333.713325733426, 14399.64000899965, 5117.667351622133, 6113.969430152821, 6135.5398345124295, 6162.0, 6181.660008699475, 827.7971896885301, 856.3620103023287, 938.4697645830084, 975.5561219645186, 1051.5163025008583, 25084.372890677878, 25102.37244068913, 25115.37211569689, 25233.243002709947, 25513.851876665594, 1055.0293729768632, 1108.3848464102339, 1138.0724709361843, 1138.527511082908, 1141.8058929981923, 11285.830712539155, 12845.357732113356, 12856.935715321551, 12865.678358040937, 12934.67663308406, 349520.0, 349753.7536936948, 350500.0, 350640.0, 351351.0, 55906.720466397404, 56175.438245617835, 56185.15722264169, 56575.71712141413, 56721.0, 1456.7377871983144, 1485.3909896942203, 1512.296781996377, 1520.9239538023276, 1526.3665578784903, 24411.51176976486, 24589.13938012161, 24611.0, 24635.137770178673, 24669.62995555104, 38990.6100938987, 52192.0, 53402.73298633637, 53455.73272133614, 53703.73148134234, 277564.0, 278500.0, 279170.0, 279191.60404198663, 279357.0, 36941.89174324775, 37088.443673345464, 37190.0, 37231.25537489289, 37363.06592335214, 44174.89562760957, 44547.777261113486, 44812.0, 46727.53272467368, 46748.065038698354, 30087.849560752056, 31050.534241985937, 31760.36479270448, 31804.840975794974, 32107.678923211402, 26074.565898875742, 26289.868550657888, 26868.05961791328, 31397.372052559276, 32478.67521324851, 31068.68931310658, 38798.80600596979, 38914.2217155661, 38934.026649333995, 38943.610563894, 30092.19446833197, 31902.202444939063, 32082.0, 32486.51270230901, 32811.343773123925, 19304.13131409118, 20737.792622073586, 21956.0, 22299.331020069425, 24773.132940347], "value": 17819.812859430556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.38996071540885, 407.5109868158209, 422.66398213420325, 433.4430257119602, 447.84773177119774, 20970.790292097074, 21758.129674813008, 21779.564408711816, 22103.889480552614, 23320.766792332113, 23292.41768955777, 23503.05987760492, 23530.35279531671, 23597.528049439003, 23620.055197792066, 5978.073398623221, 6205.193324867761, 6238.00191969285, 6246.312905580406, 6257.749690012394, 5846.386129456409, 6085.361037091108, 6141.815745527641, 6179.721912513925, 6183.103449999748, 6044.788432404866, 6105.8168254952425, 6125.264968203822, 6125.632462052269, 6128.724207410677, 5819.67991760453, 6062.878742425127, 6088.634681919099, 6092.756289748405, 6121.602095863755, 5420.91868621971, 5424.864378390533, 5431.592630552725, 5435.157550579627, 5442.6462279952075, 22836.771632283795, 27489.587656184973, 28140.718592814013, 28164.43671126566, 28180.295492612644, 1902.4102528216158, 2131.7335333083365, 2208.5251670890834, 2210.502636906699, 2211.9336419907427, 14633.853661463356, 17374.0, 18244.817551824577, 18246.817531824778, 18378.540536486562, 1796.8742188046842, 2077.7298951136254, 2083.656196727538, 2124.150339864051, 2134.647783115784, 140029.8995515068, 154131.0, 163258.0, 171463.0, 175096.12451937786, 10192.898071019343, 12308.323042232763, 12653.810192846931, 12667.809982850265, 12745.68135796594, 16484.0, 16645.084520351254, 16649.41727039548, 16672.83327166713, 16699.6660066798, 8757.781055473664, 8806.691765788295, 8981.236594889377, 9041.593128309241, 9115.589798459085, 7328.267173282629, 8249.463784853966, 8486.83026339482, 8531.146885311544, 8637.309015278763, 7704.92295077042, 7970.322522585529, 7997.280244777998, 8004.319632831158, 8009.159038301011, 2153.257126291427, 2173.3262688566597, 2174.0, 2174.0977494339986, 2328.9767102329097, 861.2421069458836, 922.7739203895088, 940.4498368454394, 941.2470023980788, 948.9857652135223, 20166.69749953752, 20721.27475538349, 20743.273985410437, 20780.425523425758, 20899.164033438494, 771.9073711154697, 914.1635403605759, 975.4878688688353, 979.6277414582555, 1009.8333774927202, 10138.594456221668, 10774.461276936121, 10880.292780969212, 10886.129109671207, 10933.890661093446, 455089.0, 491700.0, 550775.0, 563641.0, 569222.0, 117565.8243417572, 120218.79781202076, 128444.0, 129457.0, 132221.0, 1123.4831977290376, 1143.5654451308449, 1167.3871217610822, 1170.0, 1170.3153655111685, 17737.556561086076, 17863.861213683696, 17999.910000450436, 18264.36074737378, 18605.534861628566, 53948.73025634847, 54113.0, 55812.0, 55883.0, 56786.7160664194, 58952.70523647354, 76544.85182722152, 77098.6145069271, 77482.61258693671, 77620.61189694016, 8747.16901894319, 8793.340499462694, 8931.955340223518, 9002.50486223264, 9011.549422528717, 13485.528006519724, 13688.726225475235, 13803.792943106062, 13953.023288370123, 14109.929450352682, 11694.88305116938, 11748.177627565989, 11752.23610465317, 11880.643580692247, 12046.397680115784, 10717.571297148339, 10784.406857622906, 10848.426978088291, 10935.234533582572, 11115.0, 11611.709707257049, 11675.88324116782, 11959.701007474883, 12138.271703697807, 12206.572769953009, 8704.825903482022, 8832.249258813074, 8897.73306800797, 8925.866112008196, 8955.238804701674, 11716.0, 11802.881971180523, 11979.700507487383, 11997.700057498632, 12136.757264854477], "value": 10305.12165838589, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.1749321687512, 1411.738828316762, 1413.7101894111709, 1419.5954153066373, 1434.0, 11629.65111046669, 11909.344986025764, 12040.277583344994, 12051.096167787418, 12260.632181034562, 10010.249231307645, 11697.883021169786, 11862.82205766912, 11914.285142891411, 11957.760844783099, 2522.079441004034, 2552.2088152672636, 2567.1785028790796, 2569.665943427352, 2569.961450578243, 2501.8373805702663, 2537.175417989161, 2559.398541342783, 2559.6032614944716, 2560.2447278052928, 2507.4985002999383, 2524.4320027993645, 2529.3929456930296, 2529.7217306096313, 2532.8733563321853, 2479.466914613358, 2493.227099599121, 2504.1235567551394, 2504.8747562621797, 2513.7611926866916, 2152.8062474377225, 2307.48081681622, 2483.6522886795865, 2502.6110508667684, 2539.7968162547136, 11596.0, 11713.004394626467, 11723.706907327385, 11729.472173752201, 11733.178677492579, 6431.517636177306, 6645.767398141042, 6664.100346453215, 6674.799756007327, 6681.632510211936, 3846.8461261549505, 3941.8029098544957, 3994.6804255659476, 4005.3190957537, 4008.4187792769885, 1255.127686258051, 1259.2822091407902, 1262.4823822232931, 1264.8608653048157, 1285.4215602978697, 15118.924405377902, 15547.766783498144, 16299.266533006043, 16444.0, 16531.33874645013, 4391.8682439526865, 5457.508824205841, 5548.250986116904, 5596.720163991784, 5607.831765047055, 9804.0, 11964.222325548812, 11986.64040078799, 11995.280283183038, 12035.518579256906, 6358.3641635836975, 6488.416042556099, 6503.154589903283, 6512.73949042033, 6515.348465153446, 5106.617003724736, 5947.732352044169, 6158.3841615838055, 6169.074638804215, 6194.473469755121, 4284.807183676742, 4343.609075183249, 4391.912161756811, 4422.049259409242, 4425.114977004612, 1245.9626211213497, 1260.8171815086762, 1262.8295180150562, 1272.7263638317806, 1274.9107562470538, 769.288408222392, 773.7175930785336, 787.7872974297022, 789.5499565247868, 790.4664351562673, 6603.141591593062, 6829.965850170717, 6906.447484201251, 7013.3687968082095, 7057.0, 786.4180506425204, 902.3818684201343, 942.2509105261197, 946.7822400847843, 947.8436058050482, 3559.7864128152396, 3882.437046628223, 3967.0875698588907, 4360.978195109004, 4454.398656181439, 14753.114813111248, 14832.406703731946, 14933.029353092232, 15141.772873406908, 15197.316120774592, 14306.642333941734, 14830.851691482947, 14855.108693478425, 14950.327235274439, 15025.173615451255, 8519.48883067018, 8593.613287402082, 8605.870911936327, 8663.436876603128, 8672.26285765717, 5744.741486633028, 5778.017736984807, 6821.93178068213, 7061.646917653994, 7236.638168091679, 12994.115853201421, 13339.599812005656, 13356.332183390987, 13625.0, 13627.182369057888, 13601.727965440834, 13787.655308616964, 13866.376013079234, 13902.37439315213, 13991.650208744863, 9368.906310936803, 9402.95298523503, 9496.43021418717, 9500.002499737428, 9568.760780980252, 10626.0, 10646.20153488507, 10746.140308775435, 10756.408397538211, 10774.515146818256, 8557.315414766928, 9247.029061948399, 9282.535873206447, 9282.767930801783, 9413.388129771543, 9009.189172974464, 9127.726168214964, 9136.817263654557, 9159.725208243763, 9227.35408521397, 8169.714060007871, 8181.836363272582, 8231.547264900488, 8289.502629842229, 8294.253517183482, 5984.162217289496, 6037.849053773691, 6148.1699970503405, 6179.3202747698615, 6229.4393504584805, 4939.950600493949, 5073.847784566469, 6038.094285857156, 6038.5772995889865, 6233.906491402723], "value": 5351.267578551469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.3711692109609, 393.65555139253155, 395.46019683132533, 400.31827774180965, 405.456688038029, 5300.390455097665, 5510.559155267579, 6059.848628760538, 6468.223813142417, 6571.2771595124495, 6048.788292409767, 6237.313895471494, 6316.0, 6354.332795056522, 6355.745770169199, 3695.5011073505134, 4696.976515117437, 4708.634495996157, 4727.196376615975, 4736.9578692687555, 3876.9224615507674, 4724.0, 4748.264019077049, 4753.47711751707, 4767.856964291077, 4309.698321117523, 4560.5862182723295, 4675.859724208279, 4703.6472264580125, 4728.935989402391, 3802.258559580883, 4261.850835220753, 4700.600448961843, 4701.48283688794, 4748.7890587899865, 3639.253952939641, 3824.043989002749, 4155.459790227282, 4167.52073511547, 4181.560936101696, 4995.450500444948, 5085.949140508585, 6082.270127584674, 6207.751689932397, 6249.375062493714, 4910.845951201465, 5068.949310506885, 5629.493345598875, 5636.633618814764, 5637.379888212294, 6203.596766210182, 6620.966895165541, 6671.432928201109, 6789.1301957884025, 6807.353301436356, 4319.222539942809, 4671.696339737906, 4730.604471680863, 4761.547652972962, 4781.689190202661, 6516.380943810331, 6851.623160726158, 7043.436525077981, 7149.213586505481, 7157.174920395318, 4484.798184081659, 4678.643193473922, 4842.927356089662, 4935.481774413634, 4964.975175124101, 4584.816607335669, 4803.495632958559, 4872.4883887192045, 5057.861981054233, 5072.923906141411, 5197.454267301954, 5670.943290567042, 5686.9715651421475, 5695.430456954271, 5699.2590963174525, 6364.459020983176, 6619.635920024445, 6792.400027985077, 6796.8300792479595, 6827.317268273133, 1474.9336279867432, 1483.5030264861177, 1604.163233098096, 1721.1480317242795, 1724.1637805664243, 944.3436811416137, 986.9950650246702, 1023.5649848814224, 1030.7165529479303, 1040.5016775842762, 6115.908261376083, 6323.336049714806, 6343.365663433619, 6354.745810167541, 6396.392342727433, 6114.7248373822395, 6965.233824279325, 6980.336867997532, 7006.75476358325, 7038.190608080034, 4628.930566041512, 6293.905591416133, 6296.212973378328, 6325.304216536178, 6333.968330158412, 3234.0136258441403, 3608.8556457741397, 3665.1020499977676, 3673.228621989412, 3710.7216958727663, 6898.379145876795, 7077.787666370017, 7234.023406840114, 7243.927560724431, 7259.564426134449, 5402.081646120169, 6432.614043157426, 7137.96431017852, 7206.783796486009, 7255.201927787939, 6313.905291420632, 7073.469489788184, 7222.530535515175, 7226.385757210591, 7308.6345682715655, 6035.517158627387, 6806.319368063154, 6923.9307606923285, 6975.755848545276, 7117.501774875709, 5749.91375129365, 6449.968005119158, 6568.700954665829, 6901.309869013058, 6911.274316196928, 6673.799786006428, 7148.678309475982, 7156.711791877512, 7166.928330716626, 7226.855462890818, 3553.3959226930965, 4429.136318417877, 4452.532484089124, 4470.100207411902, 4475.9552404475535, 6086.817395478142, 6197.194364732556, 6906.687729331411, 6960.721571137098, 6998.510104292651, 6761.188657360952, 6768.323167683192, 6771.3905748482875, 6771.476417805954, 6775.322467753185, 5868.2077919480325, 6551.901721474277, 6764.763233286812, 6782.728690852311, 6829.634073185283, 5798.520887467485, 6071.848203794941, 6578.967105164444, 6604.471642268511, 6746.696398661974, 6429.03564465334, 6479.028145778171, 6553.705083271359, 6670.232923213698, 6708.22855371619, 5161.354830646169, 6087.512998959984, 6513.641749703812, 6537.967310163609, 6579.901301480386], "value": 4899.94092424786, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.5662307419382, 813.3858936502946, 826.6775957376628, 858.2404571953819, 865.5196366016862, 16829.579260518494, 17249.396271130496, 17286.48140555784, 19584.118714657838, 20163.899180504115, 18626.813731862676, 18945.0, 18996.24015039397, 19054.284414539172, 19119.330823421184, 996.1781530237565, 1003.9447830369327, 1015.1219195396003, 1017.6489111256604, 1020.8570800087994, 1005.4520286443906, 1029.5469993203005, 1029.6910926721991, 1030.386919782728, 1033.2715435617908, 1066.4934156275785, 1083.6911480228136, 1084.701707030569, 1086.6196831109128, 1087.5541028178409, 1052.237128082142, 1065.9520321585546, 1075.6557901471492, 1077.6228320087985, 1081.5727787523924, 795.6220795122329, 802.3380710913519, 802.5104686141449, 802.7511471443871, 803.0724513187278, 14573.927130364385, 17589.472315830546, 17993.01038442885, 18015.0, 18061.548461288574, 17712.380066697602, 17734.02462864542, 17753.201105950262, 17799.644007119914, 18122.546936326566, 14474.42102315906, 14575.052621579563, 14582.416703331854, 14782.926085369612, 14809.70380592393, 3438.862445502177, 3476.3047390521997, 3501.3522498337634, 3503.7722548034294, 3505.491703702974, 138423.99999999898, 161561.0, 161833.1908340451, 163290.0, 164678.0, 13833.515826946008, 14059.43762249519, 14177.716445671234, 14179.716405671828, 14183.0, 26318.6052209217, 26332.078377257087, 26442.206733798015, 27162.185134445997, 27346.726532734814, 7234.312740289664, 7273.309035641606, 7293.197748247689, 7330.340269375782, 7350.852982940311, 3932.508436445444, 3948.0722030323427, 3959.940600890989, 4033.4554835097474, 4037.333839916381, 4418.138462999685, 4507.729536227836, 4514.052049069666, 4517.299818528133, 4519.322101684774, 916.6929078758691, 951.3816019587324, 951.7668171298076, 960.3901522533102, 962.4033099478228, 567.6480582039162, 642.5534253693731, 655.063259538864, 678.7692184657241, 681.3186813186813, 11500.309981401142, 12343.13598048128, 12529.561465348668, 12808.871911280954, 12939.870601294055, 614.4899733221415, 634.4828964394004, 642.4539141729585, 660.2901880478505, 662.4468568745056, 5484.177373394023, 5816.563757718188, 5821.563382746311, 5829.329627092854, 5929.555283353766, 125138.0, 125882.0, 126052.36973815072, 126365.0, 130184.0, 106085.9391406076, 108310.0, 108933.0, 122474.0, 123258.38370808089, 92836.21491355405, 95544.52227738817, 95972.0, 96438.0356196443, 96727.51636241915, 11049.226554141133, 11841.881581184078, 12761.744765104831, 12895.03287253441, 13079.607611771662, 41617.0, 41659.37510937399, 41675.16649667051, 41817.58182418137, 41862.74411767652, 56523.151503035726, 57315.71342143263, 57538.424615753305, 57681.0, 57739.711301443225, 16468.17659117063, 16666.4166754163, 17055.147242638068, 17108.0, 17278.740818887476, 17949.820501794817, 18786.71819922675, 18896.0, 18916.716249256, 18937.90531047336, 17089.401870934456, 17241.91379043097, 17937.910310448366, 18070.186841591898, 18271.0, 12304.384780760747, 15995.920020400288, 16028.358865645243, 16125.596860078123, 16195.676086478441, 16595.91702041482, 16739.91190572609, 17009.914950425664, 17155.970641761534, 17389.304427822746, 10994.780104398027, 11863.822042669539, 11965.880341196476, 12212.267263964191, 12629.2422454653, 13236.293837725452, 13921.860781392461, 13939.0, 13944.930275348559, 13966.231857247543], "value": 8782.215785674789, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 113.0, 114.0, 113.0, 114.0, 121.0, 167.0, 168.0, 114.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 111.0, 146.0, 121.0, 145.0, 109.0, 106.0, 137.0, 109.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 204.0, 200.0, 208.0, 236.0, 283.0, 210.0, 201.0, 200.0, 197.0], "value": 213.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 60.0, 60.0, 82.0, 63.0, 61.0, 59.0, 60.0, 81.0, 59.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 83.0, 72.0, 69.0, 69.0, 91.0, 102.0, 102.0, 123.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 89.0, 91.0, 96.0, 90.0, 89.0, 90.0, 89.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 150.0, 95.0, 96.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 93.0, 145.0, 140.0, 81.0, 142.0, 145.0, 91.0, 89.0], "value": 111.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 54.0, 52.0, 50.0, 52.0, 52.0, 52.0, 51.0, 59.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 60.0, 64.0, 71.0, 64.0, 70.0, 78.0, 49.0, 57.0], "value": 63.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 88.0, 88.0, 88.0, 89.0, 88.0, 88.0, 120.0, 88.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 138.0, 128.0, 133.0, 144.0, 151.0, 160.0, 158.0, 159.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 241.0, 186.0, 187.0, 183.0, 188.0, 186.0, 189.0, 221.0, 186.0], "value": 196.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 68.0, 68.0, 67.0, 68.0, 69.0, 70.0, 66.0], "value": 68.0, "unit": "score"}]}]} 23:05:34 INFO - Return code: 0 23:05:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:05:34 INFO - # TBPL SUCCESS # 23:05:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:05:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:05:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:05:34 INFO - 2017-05-27 23:05:34.656 screenresolution[1680:11900] starting screenresolution argv=screenresolution get 23:05:34 INFO - 2017-05-27 23:05:34.659 screenresolution[1680:11900] Display 0: 1600x1200x32@60 23:05:34 INFO - 2017-05-27 23:05:34.665 screenresolution[1681:11903] starting screenresolution argv=screenresolution list 23:05:34 INFO - Available Modes on Display 0 23:05:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:05:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:05:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:05:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:05:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:05:34 INFO - Intel Iris: 23:05:34 INFO - Chipset Model: Intel Iris 23:05:34 INFO - Type: GPU 23:05:34 INFO - Bus: Built-In 23:05:34 INFO - VRAM (Dynamic, Max): 1536 MB 23:05:34 INFO - Vendor: Intel (0x8086) 23:05:34 INFO - Device ID: 0x0a2e 23:05:34 INFO - Revision ID: 0x0009 23:05:34 INFO - Displays: 23:05:34 INFO - Display: 23:05:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:05:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:05:34 INFO - Display Serial Number: 1600x1200 60 23:05:34 INFO - Main Display: Yes 23:05:34 INFO - Mirror: Off 23:05:34 INFO - Online: Yes 23:05:34 INFO - Rotation: Supported 23:05:34 INFO - Return code: 0 23:05:34 INFO - Running post-action listener: _resource_record_post_action 23:05:34 INFO - [mozharness: 2017-05-28 06:05:34.899197Z] Finished run-tests step (success) 23:05:34 INFO - Running post-run listener: _resource_record_post_run 23:05:34 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34149376; Write bytes: 568014336; Read time: 214; Write time: 1769 23:05:34 INFO - TinderboxPrint: CPU usage
53.4% 23:05:34 INFO - TinderboxPrint: I/O read bytes / time
34,149,376 / 214 23:05:34 INFO - TinderboxPrint: I/O write bytes / time
568,014,336 / 1,769 23:05:34 INFO - TinderboxPrint: CPU idle
2,341.9 (46.6%) 23:05:34 INFO - TinderboxPrint: CPU system
1,021.6 (20.3%) 23:05:34 INFO - TinderboxPrint: CPU user
1,662.2 (33.1%) 23:05:34 INFO - TinderboxPrint: Swap in / out
276,127,744 / 0 23:05:34 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 235678208; Write bytes: 228012032; Read time: 16953; Write time: 461 23:05:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 23:05:34 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 32089088; Write bytes: 321639936; Read time: 195; Write time: 1259 23:05:35 INFO - Running post-run listener: _upload_blobber_files 23:05:35 INFO - Blob upload gear active. 23:05:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:05:35 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 23:05:35 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'] 23:05:35 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 23:05:35 INFO - (blobuploader) - INFO - Open directory for files ... 23:05:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:05:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:05:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:37 INFO - (blobuploader) - INFO - Done attempting. 23:05:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:05:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:05:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:38 INFO - (blobuploader) - INFO - Done attempting. 23:05:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:05:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:05:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:38 INFO - (blobuploader) - INFO - Done attempting. 23:05:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:05:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:05:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:40 INFO - (blobuploader) - INFO - Done attempting. 23:05:40 INFO - (blobuploader) - INFO - Iteration through files over. 23:05:40 INFO - Return code: 0 23:05:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:05:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:05:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dcc513df01c4aa30feff7ca57312542e472fd40b6e5b03b340b57114886c12e9da0ac1fe36670f7131235c6923157b09375f2f0fb3a66f1df60684f3a26597b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009620e976e0c0e9c995a623da7725d40a7a474250abba3995ccdfe6c8c1fd6bec1e5c3ee595de8ccf109b891b71fe1ce430aed8de897ff7b7ab879d21c85f12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f4c89713c9544fb9a02445d941e30ba58cfaefe4089647092d001a450b27255ee941362e911b3b01d8fba88fc193ccf8efc6727332012e327aeb8edf156ef43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe4719cc57ec792e4500c12c6bf9f0d4a49bbf211e286406a9fd66952ab95c9739940b0d281e1f59282264503fe8f8b7dfd241fd87522fe2a9cad79dae5bdd0"} 23:05:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:05:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:05:40 INFO - Contents: 23:05:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dcc513df01c4aa30feff7ca57312542e472fd40b6e5b03b340b57114886c12e9da0ac1fe36670f7131235c6923157b09375f2f0fb3a66f1df60684f3a26597b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009620e976e0c0e9c995a623da7725d40a7a474250abba3995ccdfe6c8c1fd6bec1e5c3ee595de8ccf109b891b71fe1ce430aed8de897ff7b7ab879d21c85f12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f4c89713c9544fb9a02445d941e30ba58cfaefe4089647092d001a450b27255ee941362e911b3b01d8fba88fc193ccf8efc6727332012e327aeb8edf156ef43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe4719cc57ec792e4500c12c6bf9f0d4a49bbf211e286406a9fd66952ab95c9739940b0d281e1f59282264503fe8f8b7dfd241fd87522fe2a9cad79dae5bdd0"} 23:05:40 INFO - Running post-run listener: copy_logs_to_upload_dir 23:05:40 INFO - Copying logs to upload dir... 23:05:40 INFO - mkdir: /builds/slave/test/build/upload/logs 23:05:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.726225 ========= master_lag: 2.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-27 23:05:42.997663) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 23:05:43.000796) ========= 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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/1dcc513df01c4aa30feff7ca57312542e472fd40b6e5b03b340b57114886c12e9da0ac1fe36670f7131235c6923157b09375f2f0fb3a66f1df60684f3a26597b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009620e976e0c0e9c995a623da7725d40a7a474250abba3995ccdfe6c8c1fd6bec1e5c3ee595de8ccf109b891b71fe1ce430aed8de897ff7b7ab879d21c85f12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f4c89713c9544fb9a02445d941e30ba58cfaefe4089647092d001a450b27255ee941362e911b3b01d8fba88fc193ccf8efc6727332012e327aeb8edf156ef43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe4719cc57ec792e4500c12c6bf9f0d4a49bbf211e286406a9fd66952ab95c9739940b0d281e1f59282264503fe8f8b7dfd241fd87522fe2a9cad79dae5bdd0"} build_url:https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010966 build_url: 'https://queue.taskcluster.net/v1/task/CONU9Z3YSUuRJXwbRc1j4g/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/1dcc513df01c4aa30feff7ca57312542e472fd40b6e5b03b340b57114886c12e9da0ac1fe36670f7131235c6923157b09375f2f0fb3a66f1df60684f3a26597b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/009620e976e0c0e9c995a623da7725d40a7a474250abba3995ccdfe6c8c1fd6bec1e5c3ee595de8ccf109b891b71fe1ce430aed8de897ff7b7ab879d21c85f12", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f4c89713c9544fb9a02445d941e30ba58cfaefe4089647092d001a450b27255ee941362e911b3b01d8fba88fc193ccf8efc6727332012e327aeb8edf156ef43", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfe4719cc57ec792e4500c12c6bf9f0d4a49bbf211e286406a9fd66952ab95c9739940b0d281e1f59282264503fe8f8b7dfd241fd87522fe2a9cad79dae5bdd0"}' ========= master_lag: 0.09 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 23:05:43.104079) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 23:05:43.104469) ========= 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.yp3zh4Ey2z/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.JKPpf8WxqS/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.005150 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 23:05:43.131033) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 23:05:43.131375) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 23:05:43.131791) ========= ========= Total master_lag: 4.61 =========