Error generating node graph #147
Description
What Versions are you running?
OS Version: MacOS 10.14.5 (18F132)
VSCode Version: Version 1.35.1 (1.35.1)
Puppet Extension Version: 0.18.1
Puppet Agent Version: 6.4.0
PDK Version (if applicable): 1.10.0
What You Are Seeing?
I have cloned the puppetlabs-ntp module and try to generate a node graph from the manifests/service.pp manifest file by selecting the command "Open Node Graph to the Side".
Instead of the node graph, an error message appears in a new window:
An internal error occured while generating the the node graph. Please see the debug log files for more information.
What is Expected?
I expect the node graph to appear.
How Did You Get This To Happen? (Steps to Reproduce)
- clone the puppetlabs-ntp module from https://github.com/puppetlabs/puppetlabs-ntp
cd puppetlabs-ntpcode .- navigate to manifests/service.pp
- select "Open Node Graph to the Side" from the "..." menu on the top right
Output Log
I, [2019-06-17T17:13:53.496128 #36085] INFO -- : Language Server is v0.19.1
D, [2019-06-17T17:13:53.496533 #36085] DEBUG -- : Loading gems...
I, [2019-06-17T17:13:55.611823 #36085] INFO -- : Using Puppet v6.4.0
D, [2019-06-17T17:13:55.612029 #36085] DEBUG -- : Detected additional puppet settings []
I, [2019-06-17T17:13:55.627684 #36085] INFO -- : Initializing Puppet Helper...
D, [2019-06-17T17:13:55.627933 #36085] DEBUG -- : Initializing Document Store...
I, [2019-06-17T17:13:55.628103 #36085] INFO -- : Initializing settings...
I, [2019-06-17T17:13:55.628559 #36085] INFO -- : Using Facter v2.5.1
I, [2019-06-17T17:13:55.630248 #36085] INFO -- : Starting RPC Server...
I, [2019-06-17T17:13:55.630472 #36085] INFO -- : Preloading Puppet Types (Async)...
D, [2019-06-17T17:13:55.630658 #36085] DEBUG -- : Using STDIO
I, [2019-06-17T17:13:55.630861 #36085] INFO -- : Preloading Facter (Async)...
I, [2019-06-17T17:13:55.631275 #36085] INFO -- : Client has connected to the language server
D, [2019-06-17T17:13:55.631116 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "default_types", "--puppet-version=6.4.0"]
D, [2019-06-17T17:13:55.633389 #36085] DEBUG -- : STDIOSRV: Starting STDIO server...
I, [2019-06-17T17:13:55.631422 #36085] INFO -- : Preloading Functions (Async)...
D, [2019-06-17T17:13:55.636775 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":0,"method":"initialize","params":{"processId":36036,"rootPath":"/Users/dimitri.tischenko/git/puppetlabs-ntp","rootUri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp","capabilities":{"workspace":{"applyEdit":true,"workspaceEdit":{"documentChanges":true,"resourceOperations":["create","rename","delete"],"failureHandling":"textOnlyTransactional"},"didChangeConfiguration":{"dynamicRegistration":true},"didChangeWatchedFiles":{"dynamicRegistration":true},"symbol":{"dynamicRegistration":true,"symbolKind":{"valueSet":[1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25,26]}},"executeCommand":{"dynamicRegistration":true},"configuration":true,"workspaceFolders":true},"textDocument":{"publishDiagnostics":{"relatedInformation":true},"synchronization":{"dynamicRegistration":true,"willSave":true,"willSaveWaitUntil":true,"didSave":true},"completion":{"dynamicRegistration":true,"contextSupport":true,"completionItem":{"snippetSupport":true,"commitCharactersSupport":true,"documentationFormat":["markdown","plaintext"],"deprecatedSupport":true,"preselectSupport":true},"completionItemKind":{"valueSet":[1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25]}},"hover":{"dynamicRegistration":true,"contentFormat":["markdown","plaintext"]},"signatureHelp":{"dynamicRegistration":true,"signatureInformation":{"documentationFormat":["markdown","plaintext"]}},"definition":{"dynamicRegistration":true},"references":{"dynamicRegistration":true},"documentHighlight":{"dynamicRegistration":true},"documentSymbol":{"dynamicRegistration":true,"symbolKind":{"valueSet":[1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,24,25,26]},"hierarchicalDocumentSymbolSupport":true},"codeAction":{"dynamicRegistration":true,"codeActionLiteralSupport":{"codeActionKind":{"valueSet":["","quickfix","refactor","refactor.extract","refactor.inline","refactor.rewrite","source","source.organizeImports"]}}},"codeLens":{"dynamicRegistration":true},"formatting":{"dynamicRegistration":true},"rangeFormatting":{"dynamicRegistration":true},"onTypeFormatting":{"dynamicRegistration":true},"rename":{"dynamicRegistration":true,"prepareSupport":true},"documentLink":{"dynamicRegistration":true},"typeDefinition":{"dynamicRegistration":true},"implementation":{"dynamicRegistration":true},"colorProvider":{"dynamicRegistration":true},"foldingRange":{"dynamicRegistration":true,"rangeLimit":5000,"lineFoldingOnly":true}}},"trace":"off","workspaceFolders":[{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp","name":"puppetlabs-ntp"}]}}
---
I, [2019-06-17T17:13:55.636945 #36085] INFO -- : Preloading Classes (Async)...
D, [2019-06-17T17:13:55.637239 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "default_functions", "--puppet-version=6.4.0"]
D, [2019-06-17T17:13:55.637692 #36085] DEBUG -- : Received initialize method
I, [2019-06-17T17:13:55.638203 #36085] INFO -- : Preloading Workspace (Async)...
D, [2019-06-17T17:13:55.640875 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":0,"result":{"capabilities":{"textDocumentSync":1,"hoverProvider":true,"completionProvider":{"resolveProvider":true,"triggerCharacters":[">","$","[","="]},"definitionProvider":true,"documentSymbolProvider":true,"workspaceSymbolProvider":true}}}
---
D, [2019-06-17T17:13:55.642539 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","method":"initialized","params":{}}
---
I, [2019-06-17T17:13:55.642970 #36085] INFO -- : Client has received initialization
D, [2019-06-17T17:13:55.643686 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","method":"textDocument/didOpen","params":{"textDocument":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp","languageId":"puppet","version":1,"text":"# @summary\n# This class handles the ntp service.\n#\n# @api private\n#\nclass ntp::service {\n\n if $ntp::service_manage == true {\n service { 'ntp':\n ensure => $ntp::service_ensure,\n enable => $ntp::service_enable,\n name => $ntp::service_name,\n provider => $ntp::service_provider,\n hasstatus => $ntp::service_hasstatus,\n hasrestart => $ntp::service_hasrestart,\n }\n }\n\n}\n"}}}
---
I, [2019-06-17T17:13:55.644196 #36085] INFO -- : Received textDocument/didOpen notification.
D, [2019-06-17T17:13:55.656988 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":1,"method":"textDocument/documentSymbol","params":{"textDocument":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"}}}
---
D, [2019-06-17T17:13:55.667318 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":1,"result":[{"kind":5,"detail":"ntp::service","children":[{"kind":6,"detail":"service: 'ntp'","children":[{"kind":13,"detail":"ensure","children":[],"range":{"end":{"character":40,"line":9},"start":{"character":6,"line":9}},"name":"ensure","selectionRange":{"end":{"character":12,"line":9},"start":{"character":6,"line":9}}},{"kind":13,"detail":"enable","children":[],"range":{"end":{"character":40,"line":10},"start":{"character":6,"line":10}},"name":"enable","selectionRange":{"end":{"character":12,"line":10},"start":{"character":6,"line":10}}},{"kind":13,"detail":"name","children":[],"range":{"end":{"character":38,"line":11},"start":{"character":6,"line":11}},"name":"name","selectionRange":{"end":{"character":10,"line":11},"start":{"character":6,"line":11}}},{"kind":13,"detail":"provider","children":[],"range":{"end":{"character":42,"line":12},"start":{"character":6,"line":12}},"name":"provider","selectionRange":{"end":{"character":14,"line":12},"start":{"character":6,"line":12}}},{"kind":13,"detail":"hasstatus","children":[],"range":{"end":{"character":43,"line":13},"start":{"character":6,"line":13}},"name":"hasstatus","selectionRange":{"end":{"character":15,"line":13},"start":{"character":6,"line":13}}},{"kind":13,"detail":"hasrestart","children":[],"range":{"end":{"character":44,"line":14},"start":{"character":6,"line":14}},"name":"hasrestart","selectionRange":{"end":{"character":16,"line":14},"start":{"character":6,"line":14}}}],"range":{"end":{"character":5,"line":15},"start":{"character":4,"line":8}},"name":"service: 'ntp'","selectionRange":{"start":{"line":8,"character":14},"end":{"line":8,"character":19}}}],"range":{"end":{"character":1,"line":18},"start":{"character":0,"line":5}},"name":"ntp::service","selectionRange":{"end":{"character":1,"line":18},"start":{"character":0,"line":5}}}]}
---
D, [2019-06-17T17:13:55.700399 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","method":"textDocument/publishDiagnostics","params":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp","diagnostics":[]}}
---
D, [2019-06-17T17:13:55.781873 #36085] DEBUG -- : [PUPPET LOG] [warning] Error loading fact /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/ec2.rb: cannot load such file -- cfpropertylist
D, [2019-06-17T17:13:55.801403 #36085] DEBUG -- : [PUPPET LOG] [warning] Error loading fact /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/macosx.rb: cannot load such file -- cfpropertylist
D, [2019-06-17T17:13:55.811705 #36085] DEBUG -- : [PUPPET LOG] [warning] Error loading fact /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processor.rb: cannot load such file -- cfpropertylist
E, [2019-06-17T17:13:55.884181 #36085] ERROR -- : [FacterHelper::_load_facts] Error loading facts (LoadError) cannot load such file -- cfpropertylist ["/opt/puppetlabs/pdk/private/ruby/2.5.3/lib/ruby/site_ruby/2.5.0/rubygems/core_ext/kernel_require.rb:54:in `require'", "/opt/puppetlabs/pdk/private/ruby/2.5.3/lib/ruby/site_ruby/2.5.0/rubygems/core_ext/kernel_require.rb:54:in `require'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:147:in `initialize'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:19:in `new'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:19:in `implementation'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors.rb:31:in `os'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors.rb:27:in `block (2 levels) in <top (required)>'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/confine.rb:40:in `true?'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `block in suitable?'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `each'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `all?'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `suitable?'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `block in find_suitable_resolutions'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `each'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `find_all'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `find_suitable_resolutions'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:112:in `block in value'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:145:in `searching'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:110:in `value'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:119:in `block in to_hash'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `each'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `inject'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `to_hash'", "/opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter.rb:127:in `to_hash'", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver/facter_helper.rb:52:in `_load_facts'", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver/facter_helper.rb:26:in `block in load_facts'", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver/facter_helper.rb:25:in `synchronize'", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver/facter_helper.rb:25:in `load_facts'", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver/facter_helper.rb:16:in `block in load_facts_async'"]
D, [2019-06-17T17:13:55.884546 #36085] DEBUG -- : [FacterHelper::_load_facts] Finished loading 0 facts
D, [2019-06-17T17:13:56.646604 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":2,"method":"puppet/getVersion","params":null}
---
D, [2019-06-17T17:13:56.648319 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":2,"result":{"puppetVersion":"6.4.0","facterVersion":"2.5.1","languageServerVersion":null,"factsLoaded":true,"functionsLoaded":false,"typesLoaded":false,"classesLoaded":false}}
---
D, [2019-06-17T17:13:57.646490 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":3,"method":"puppet/getVersion","params":null}
---
D, [2019-06-17T17:13:57.647058 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":3,"result":{"puppetVersion":"6.4.0","facterVersion":"2.5.1","languageServerVersion":null,"factsLoaded":true,"functionsLoaded":false,"typesLoaded":false,"classesLoaded":false}}
---
A, [2019-06-17T17:13:58.322644 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action default_functions --puppet-version=6.4.0 returned exitcode 0,
D, [2019-06-17T17:13:58.354684 #36085] DEBUG -- : SidecarQueue Thread: default_functions returned 204 items
D, [2019-06-17T17:13:58.354984 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "default_classes", "--puppet-version=6.4.0"]
D, [2019-06-17T17:13:58.651023 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":4,"method":"puppet/getVersion","params":null}
---
D, [2019-06-17T17:13:58.651696 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":4,"result":{"puppetVersion":"6.4.0","facterVersion":"2.5.1","languageServerVersion":null,"factsLoaded":true,"functionsLoaded":true,"typesLoaded":false,"classesLoaded":false}}
---
D, [2019-06-17T17:13:59.654539 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":5,"method":"puppet/getVersion","params":null}
---
D, [2019-06-17T17:13:59.655256 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":5,"result":{"puppetVersion":"6.4.0","facterVersion":"2.5.1","languageServerVersion":null,"factsLoaded":true,"functionsLoaded":true,"typesLoaded":false,"classesLoaded":false}}
---
A, [2019-06-17T17:14:00.391665 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action default_types --puppet-version=6.4.0 returned exitcode 0,
D, [2019-06-17T17:14:00.844582 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":6,"method":"puppet/getVersion","params":null}
---
D, [2019-06-17T17:14:01.052581 #36085] DEBUG -- : SidecarQueue Thread: default_types returned 339 items
D, [2019-06-17T17:14:01.053208 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":6,"result":{"puppetVersion":"6.4.0","facterVersion":"2.5.1","languageServerVersion":null,"factsLoaded":true,"functionsLoaded":true,"typesLoaded":false,"classesLoaded":false}}
---
A, [2019-06-17T17:14:01.053458 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action default_classes --puppet-version=6.4.0 returned exitcode 0,
D, [2019-06-17T17:14:01.053784 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "workspace_classes", "--local-workspace", "/Users/dimitri.tischenko/git/puppetlabs-ntp", "--puppet-version=6.4.0"]
D, [2019-06-17T17:14:01.064973 #36085] DEBUG -- : SidecarQueue Thread: default_classes returned 56 items
D, [2019-06-17T17:14:01.070432 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "workspace_functions", "--local-workspace", "/Users/dimitri.tischenko/git/puppetlabs-ntp", "--puppet-version=6.4.0"]
D, [2019-06-17T17:14:01.659638 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":7,"method":"puppet/getVersion","params":null}
---
D, [2019-06-17T17:14:01.660204 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":7,"result":{"puppetVersion":"6.4.0","facterVersion":"2.5.1","languageServerVersion":null,"factsLoaded":true,"functionsLoaded":true,"typesLoaded":true,"classesLoaded":true}}
---
A, [2019-06-17T17:14:03.107360 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action workspace_classes --local-workspace /Users/dimitri.tischenko/git/puppetlabs-ntp --puppet-version=6.4.0 returned exitcode 0,
D, [2019-06-17T17:14:03.110616 #36085] DEBUG -- : SidecarQueue Thread: workspace_classes returned 4 items
D, [2019-06-17T17:14:03.110851 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "workspace_types", "--local-workspace", "/Users/dimitri.tischenko/git/puppetlabs-ntp", "--puppet-version=6.4.0"]
A, [2019-06-17T17:14:03.615564 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action workspace_functions --local-workspace /Users/dimitri.tischenko/git/puppetlabs-ntp --puppet-version=6.4.0 returned exitcode 0,
D, [2019-06-17T17:14:03.616928 #36085] DEBUG -- : SidecarQueue Thread: workspace_functions returned 0 items
A, [2019-06-17T17:14:06.028853 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action workspace_types --local-workspace /Users/dimitri.tischenko/git/puppetlabs-ntp --puppet-version=6.4.0 returned exitcode 0,
D, [2019-06-17T17:14:06.030252 #36085] DEBUG -- : SidecarQueue Thread: workspace_types returned 0 items
D, [2019-06-17T17:14:13.796930 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":8,"method":"puppet/compileNodeGraph","params":{"external":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"}}
---
D, [2019-06-17T17:14:13.798663 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "node_graph", "--action-parameters={\"source\":\"/var/folders/yx/cf50yz2j26x0chs5_1gqtcz40000gq/T/langserver-sidecar20190617-36085-776d15\"}", "--local-workspace=/Users/dimitri.tischenko/git/puppetlabs-ntp", "--puppet-version=6.4.0"]
A, [2019-06-17T17:14:16.002918 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action node_graph --action-parameters={"source":"/var/folders/yx/cf50yz2j26x0chs5_1gqtcz40000gq/T/langserver-sidecar20190617-36085-776d15"} --local-workspace=/Users/dimitri.tischenko/git/puppetlabs-ntp --puppet-version=6.4.0 returned exitcode 1, /opt/puppetlabs/pdk/private/ruby/2.5.3/lib/ruby/site_ruby/2.5.0/rubygems/core_ext/kernel_require.rb:54:in `require': cannot load such file -- cfpropertylist (LoadError)
from /opt/puppetlabs/pdk/private/ruby/2.5.3/lib/ruby/site_ruby/2.5.0/rubygems/core_ext/kernel_require.rb:54:in `require'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:147:in `initialize'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:19:in `new'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:19:in `implementation'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors.rb:31:in `os'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors.rb:27:in `block (2 levels) in <top (required)>'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/confine.rb:40:in `true?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `block in suitable?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `each'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `all?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `suitable?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `block in find_suitable_resolutions'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `each'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `find_all'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `find_suitable_resolutions'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:112:in `block in value'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:145:in `searching'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:110:in `value'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:119:in `block in to_hash'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `each'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `inject'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `to_hash'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter.rb:127:in `to_hash'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:38:in `compile_to_ral'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:47:in `compile_to_relationship_graph'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:54:in `compile_to_pretty_relationship_graph'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:16:in `compile_node_graph'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet_languageserver_sidecar.rb:244:in `execute'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet_languageserver_sidecar.rb:294:in `execute_and_output'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar:12:in `<main>'
E, [2019-06-17T17:14:16.007774 #36085] ERROR -- : (puppet/compileNodeGraph) Error generating node graph. undefined method `dot_content' for nil:NilClass
D, [2019-06-17T17:14:16.008292 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":8,"result":{"dotContent":null,"error":"An internal error occured while generating the the node graph. Please see the debug log files for more information."}}
---
D, [2019-06-17T17:16:49.825874 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":9,"method":"textDocument/hover","params":{"textDocument":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"},"position":{"line":11,"character":23}}}
---
D, [2019-06-17T17:16:49.832790 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":9,"result":{"contents":null}}
---
D, [2019-06-17T17:17:59.235566 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":10,"method":"textDocument/hover","params":{"textDocument":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"},"position":{"line":10,"character":27}}}
---
D, [2019-06-17T17:17:59.242867 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":10,"result":{"contents":null}}
---
D, [2019-06-17T17:17:59.832136 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":11,"method":"textDocument/hover","params":{"textDocument":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"},"position":{"line":5,"character":17}}}
---
E, [2019-06-17T17:17:59.839724 #36085] ERROR -- : (textDocument/hover) Unable to generate Hover information for object of type Puppet::Pops::Model::HostClassDefinition
D, [2019-06-17T17:17:59.840144 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":11,"result":{"contents":null}}
---
D, [2019-06-17T17:18:52.492319 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":12,"method":"textDocument/hover","params":{"textDocument":{"uri":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"},"position":{"line":10,"character":22}}}
---
D, [2019-06-17T17:18:52.501083 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":12,"result":{"contents":null}}
---
D, [2019-06-17T17:18:55.962341 #36085] DEBUG -- : --- INBOUND
{"jsonrpc":"2.0","id":13,"method":"puppet/compileNodeGraph","params":{"external":"file:///Users/dimitri.tischenko/git/puppetlabs-ntp/manifests/service.pp"}}
---
D, [2019-06-17T17:18:55.965333 #36085] DEBUG -- : SidecarQueue Thread: Running sidecar ["ruby", "/Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar", "--action", "node_graph", "--action-parameters={\"source\":\"/var/folders/yx/cf50yz2j26x0chs5_1gqtcz40000gq/T/langserver-sidecar20190617-36085-rbcscr\"}", "--local-workspace=/Users/dimitri.tischenko/git/puppetlabs-ntp", "--puppet-version=6.4.0"]
A, [2019-06-17T17:18:58.681854 #36085] ANY -- : SidecarQueue Thread: Calling sidecar with --action node_graph --action-parameters={"source":"/var/folders/yx/cf50yz2j26x0chs5_1gqtcz40000gq/T/langserver-sidecar20190617-36085-rbcscr"} --local-workspace=/Users/dimitri.tischenko/git/puppetlabs-ntp --puppet-version=6.4.0 returned exitcode 1, /opt/puppetlabs/pdk/private/ruby/2.5.3/lib/ruby/site_ruby/2.5.0/rubygems/core_ext/kernel_require.rb:54:in `require': cannot load such file -- cfpropertylist (LoadError)
from /opt/puppetlabs/pdk/private/ruby/2.5.3/lib/ruby/site_ruby/2.5.0/rubygems/core_ext/kernel_require.rb:54:in `require'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:147:in `initialize'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:19:in `new'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors/os.rb:19:in `implementation'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors.rb:31:in `os'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/processors.rb:27:in `block (2 levels) in <top (required)>'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/confine.rb:40:in `true?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `block in suitable?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `each'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `all?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/core/suitable.rb:111:in `suitable?'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `block in find_suitable_resolutions'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `each'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `find_all'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:152:in `find_suitable_resolutions'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:112:in `block in value'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:145:in `searching'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/fact.rb:110:in `value'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:119:in `block in to_hash'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `each'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `inject'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter/util/collection.rb:118:in `to_hash'
from /opt/puppetlabs/pdk/private/puppet/ruby/2.5.0/gems/facter-2.5.1/lib/facter.rb:127:in `to_hash'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:38:in `compile_to_ral'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:47:in `compile_to_relationship_graph'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:54:in `compile_to_pretty_relationship_graph'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet-languageserver-sidecar/puppet_parser_helper.rb:16:in `compile_node_graph'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet_languageserver_sidecar.rb:244:in `execute'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/lib/puppet_languageserver_sidecar.rb:294:in `execute_and_output'
from /Users/dimitri.tischenko/.vscode/extensions/jpogran.puppet-vscode-0.18.1/vendor/languageserver/puppet-languageserver-sidecar:12:in `<main>'
E, [2019-06-17T17:18:58.686814 #36085] ERROR -- : (puppet/compileNodeGraph) Error generating node graph. undefined method `dot_content' for nil:NilClass
D, [2019-06-17T17:18:58.687291 #36085] DEBUG -- : --- OUTBOUND
{"jsonrpc":"2.0","id":13,"result":{"dotContent":null,"error":"An internal error occured while generating the the node graph. Please see the debug log files for more information."}}
---