search

chef / knife-windows

Plugin for Chef's knife tool for working with Windows nodes
Apache License 2.0
152 stars 110 forks source link

knife winrm prints double log messages #447

Closed bish0polis closed 5 years ago

bish0polis commented 6 years ago

Hey guys,

This is a weird one -- winrm prints double log messages, and I'm stuck wearing my baffled face:

knife winrm "name:$hostname" -a ipaddress -x "Administrator" -P '$localpw' -- chef-client -W -o myrecipe
:
Uploaded 6 cookbooks.
192.168.1.229 Starting Chef Client, version 13.8.0
192.168.1.229
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: *** Chef 13.8.0 ***
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: *** Chef 13.8.0 ***
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: Platform: x64-mingw32
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: Platform: x64-mingw32
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: Chef-client pid: 2600
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: Chef-client pid: 2600
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: The plugin path C:\chef\ohai\plugins does not exist. Skipping...
192.168.1.229 [2018-03-10T15:34:34-05:00] INFO: The plugin path C:\chef\ohai\plugins does not exist. Skipping...
192.168.1.229 [2018-03-10T15:34:37-05:00] WARN: Run List override has been provided.
192.168.1.229 [2018-03-10T15:34:37-05:00] WARN: Run List override has been provided.
192.168.1.229 [2018-03-10T15:34:37-05:00] WARN: Original Run List: []
192.168.1.229 [2018-03-10T15:34:37-05:00] WARN: Original Run List: []
192.168.1.229 [2018-03-10T15:34:37-05:00] WARN: Overridden Run List: [recipe[myrecipe]]
192.168.1.229 [2018-03-10T15:34:37-05:00] WARN: Overridden Run List: [recipe[myrecipe]]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Run List is [recipe[myrecipe]]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Run List is [recipe[myrecipe]]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Run List expands to [myrecipe]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Run List expands to [myrecipe]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Starting Chef Run for hostname
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Starting Chef Run for hostname
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Running start handlers
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Running start handlers
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Start handlers complete.
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Start handlers complete.
192.168.1.229 resolving cookbooks for run list: ["myrecipe"]
192.168.1.229
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Loading cookbooks [...]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Loading cookbooks [...]
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Skipping removal of obsoleted cookbooks from the cache
192.168.1.229 [2018-03-10T15:34:37-05:00] INFO: Skipping removal of obsoleted cookbooks from the cache
192.168.1.229 Synchronizing Cookbooks:
:
192.168.1.229 Installing Cookbook Gems:
192.168.1.229 Compiling Cookbooks...
192.168.1.229
:
192.168.1.229 Converging 1 resources
192.168.1.229
:
192.168.1.229
192.168.1.229 [2018-03-10T15:34:38-05:00] WARN: Skipping final node save because override_runlist was given
192.168.1.229 [2018-03-10T15:34:38-05:00] WARN: Skipping final node save because override_runlist was given
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Chef Run complete in 1.28125 seconds
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Chef Run complete in 1.28125 seconds
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Skipping removal of unused files from the cache
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Skipping removal of unused files from the cache
192.168.1.229
192.168.1.229 Running handlers:
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Running report handlers
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Running report handlers
192.168.1.229 Running handlers complete
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Report handlers complete
192.168.1.229 [2018-03-10T15:34:38-05:00] INFO: Report handlers complete
192.168.1.229 Chef Client finished, 0/1 resources would have been updated

I hid or changed lines with hostnames or custom cookbook names, but I can add them back in privately. I'm thinking there's something obvious (to you; not to me yet) in my config that you know will cause (most) lines to be doubled.

The slightest clue you need or theory you have, let me know?

dheerajd-msys commented 6 years ago

Hello @bish0polis, I've tried to reproduce the issue in one of my Windows 2012 R2 VM with chef v14.3.37 as well as with what you are using i.e. chef v13.8.0. But In my case it doesn't print the double log messages.

Command 


PS E:\Backup\Project\Chef_Repo\knife-windows> bundle exec knife winrm "name:dh-winrm-win12" -c ..\..\chef-starter\chef-repo\.chef\knife.rb -a fqdn -x "azureuser" -P "azure@12345678" -- chef-client -W -o starter

Output

dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Starting Chef Client, version 13.8.0
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:06+00:00] INFO: *** Chef 13.8.0 ***
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:06+00:00] INFO: Platform: x64-mingw32
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:06+00:00] INFO: Chef-client pid: 464
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:06+00:00] INFO: The plugin path C:\chef\ohai\plugins does not exist. Skipping...
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] WARN: Run List override has been provided.
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] WARN: Original Run List: [recipe[starter]]
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] WARN: Overridden Run List: [recipe[starter]]
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Run List is [recipe[starter]]
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Run List expands to [starter]
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Starting Chef Run for dh-winrm-win12
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Running start handlers
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Start handlers complete.
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net resolving cookbooks for run list: ["starter"]
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Loading cookbooks [starter@1.0.0]
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Skipping removal of obsoleted cookbooks from the cache
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Synchronizing Cookbooks:
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net   - starter (1.0.0)
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Installing Cookbook Gems:
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Compiling Cookbooks...
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Converging 1 resources
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Recipe: starter::default
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net   * log[Welcome to Chef, Sam Doe!] action write[2018-07-26T12:39:10+00:00] INFO: Processing log[Welcome to Chef, Sam Doe!] action write (starter::default line 4)
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Welcome to Chef, Sam Doe!
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] WARN: Skipping final node save because override_runlist was given
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Chef Run complete in 0.82328 seconds
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Skipping removal of unused files from the cache
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Running handlers:
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Running report handlers
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Running handlers complete
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net [2018-07-26T12:39:10+00:00] INFO: Report handlers complete
dh-winrm-win12.yuqpkniyxd1elhoukjmsd1ld0f.dx.internal.cloudapp.net Chef Client finished, 1/1 resources would have been updated

Could you please let me know if you tested it on any other Windows?

dheerajd-msys commented 5 years ago

Closing this issue as it wasn't reproduced. Please feel free to re-open if you still confront with same issue. Thanks