Jira (FACT-3001) Debug output shows facter gets called thousands of times

[Kevin Reeuwijk (Jira)]

Kevin Reeuwijk created an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times Issue Type: Improvement Affects Versions: FACT 4.0.51 Assignee: Unassigned Components: Facter 4 Created: 2021/03/29 8:58 AM Environment: Puppet Agent 7.4.1 agent run on Windows with --debug enabled: Priority: Normal Reporter: Kevin Reeuwijk When running a puppet agent run with --debug, the following block appears a whole lot in the output: 2021-03-29 03:38:48 -0700 Facter (debug): Loading internal facts 2021-03-29 03:38:48 -0700 Facter (debug): Loading all internal facts 2021-03-29 03:38:48 -0700 Facter (debug): Loading external facts 2021-03-29 03:38:48 -0700 Facter (debug): Loading custom facts 2021-03-29 03:38:48 -0700 Facter (debug): Loading external facts 2021-03-29 03:38:48 -0700 Facter (debug): User query is: ["operatingsystem"] 2021-03-29 03:38:48 -0700 Facter (debug): Query is operatingsystem 2021-03-29 03:38:48 -0700 Facter (debug): Checking query tokens operatingsystem 2021-03-29 03:38:48 -0700 Facter (debug): List of resolvable facts: [#<Facter::SearchedFact:0x000000000a269198 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @filter_tokens=[], @user_query="operatingsystem", @type=:legacy, @file=nil>] 2021-03-29 03:38:48 -0700 Facter (debug): Resolving facts sequentially 2021-03-29 03:38:48 -0700 Facter (debug): fact "operatingsystem" has resolved to: windows In my run this was in the logs 3054 times. Mostly it's for the operatingsystem and fqdn fact, but also a few others. It would seem that this is resource inefficient and could be improved. Add Comment

This message was sent by Atlassian Jira (v8.5.2#805002-sha1:a66f935)

[Ciprian Badescu (Jira)]

Ciprian Badescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Ciprian Badescu Epic Link: FACT-3051

Add Comment

This message was sent by Atlassian Jira (v8.13.2#813002-sha1:c495a97)

[Ciprian Badescu (Jira)]

Ciprian Badescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Ciprian Badescu Team: Night's Watch

Add Comment

[Ciprian Badescu (Jira)]

Ciprian Badescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Ciprian Badescu Sprint: NW-2021-07-14

Add Comment

[Ciprian Badescu (Jira)]

Ciprian Badescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Ciprian Badescu Story Points: 2

Add Comment

[Ciprian Badescu (Jira)]

Ciprian Badescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Ciprian Badescu Sprint: NW-2021-07- 14 28

Add Comment

[Gheorghe Popescu (Jira)]

Gheorghe Popescu assigned an issue to Gheorghe Popescu

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Gheorghe Popescu Assignee: Gheorghe Popescu

Add Comment

[Gheorghe Popescu (Jira)]

Gheorghe Popescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Gheorghe Popescu Release Notes: Enhancement

Add Comment

[Gheorghe Popescu (Jira)]

Gheorghe Popescu updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Gheorghe Popescu Fix Version/s: FACT 4.2.3

Add Comment

[Claire Cadman (Jira)]

Claire Cadman updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Claire Cadman Labels: doc-reviewed

Add Comment

[Caleb Ku (Jira)]

Caleb Ku commented on FACT-3001

Re: Debug output shows facter gets called thousands of times I'm still seeing this issue, even after updating Puppet Agent to 7.10.0, updating Puppet Server to 7.3.0, and verifying that Facter on both server and client is 4.2.3.

Add Comment

[Ciprian Badescu (Jira)]

Ciprian Badescu commented on FACT-3001

Re: Debug output shows facter gets called thousands of times

Caleb Ku, can you provide us the logs?

Add Comment

[Caleb Ku (Jira)]

Caleb Ku commented on FACT-3001

Re: Debug output shows facter gets called thousands of times

Ciprian Badescu, here is the beginning of a log. Let me know if you need any more. I obscured the system FQDN.   Debug: Could not find library 'syslog' required to enable feature 'syslog' Debug: Applying settings catalog for sections main, agent, ssl Debug: Could not find library 'selinux' required to enable feature 'selinux' Debug: Using settings: adding file resource 'confdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'codedir': 'File[C:/ProgramData/PuppetLabs/code]{:path=>"C:/ProgramData/PuppetLabs/code", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'vardir': 'File[C:/ProgramData/PuppetLabs/puppet/cache]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'logdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/log]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/log", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'statedir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'rundir': 'File[C:/ProgramData/PuppetLabs/puppet/var/run]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/run", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'libdir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/lib]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/lib", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'hiera_config': 'File[C:/ProgramData/PuppetLabs/puppet/etc/hiera.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/hiera.yaml", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'preview_outputdir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/preview]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/preview", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'certdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'ssldir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'publickeydir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'requestdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'privatekeydir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'privatedir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'hostcert': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/COMPUTERNAME.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/COMPUTERNAME.pem", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'hostprivkey': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/COMPUTERNAME.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/COMPUTERNAME.pem", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'localcacert': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'hostcrl': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'statefile': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state/state.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state/state.yaml", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'transactionstorefile': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state/transactionstore.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state/transactionstore.yaml", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'clientyamldir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/client_yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/client_yaml", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'client_datadir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/client_data]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/client_data", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'classfile': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state/classes.txt]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state/classes.txt", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'resourcefile': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state/resources.txt]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state/resources.txt", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'deviceconfdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/devices]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/devices", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'clientbucketdir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/clientbucket]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/clientbucket", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'publicdir': 'File[C:/ProgramData/PuppetLabs/puppet/public]{:path=>"C:/ProgramData/PuppetLabs/puppet/public", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'lastrunfile': 'File[C:/ProgramData/PuppetLabs/puppet/public/last_run_summary.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/public/last_run_summary.yaml", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'lastrunreport': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state/last_run_report.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state/last_run_report.yaml", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'graphdir': 'File[C:/ProgramData/PuppetLabs/puppet/cache/state/graphs]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/state/graphs", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'pluginfactdest': 'File[C:/ProgramData/PuppetLabs/puppet/cache/facts.d]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/facts.d", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Using settings: adding file resource 'localedest': 'File[C:/ProgramData/PuppetLabs/puppet/cache/locales]{:path=>"C:/ProgramData/PuppetLabs/puppet/cache/locales", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}' Debug: Puppet::Type::File::ProviderPosix: feature posix is missing Debug: Facter: Resolving facts sequentially Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000007683498 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Could not find library 'cfpropertylist' required to enable feature 'cfpropertylist' Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000088282e8 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Could not find library 'shadow' required to enable feature 'libshadow' Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009b28d20 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/lib]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/hiera.yaml]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/preview]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/COMPUTERNAME.pem]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/COMPUTERNAME.pem]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc/ssl] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state/state.yaml]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache/state] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state/transactionstore.yaml]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache/state] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/client_yaml]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/client_data]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state/classes.txt]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache/state] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state/resources.txt]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache/state] Debug: /File[C:/ProgramData/PuppetLabs/puppet/etc/devices]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/etc] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/clientbucket]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/public/last_run_summary.yaml]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/public] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state/last_run_report.yaml]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache/state] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/state/graphs]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache/state] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/facts.d]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: /File[C:/ProgramData/PuppetLabs/puppet/cache/locales]: Adding autorequire relationship with File[C:/ProgramData/PuppetLabs/puppet/cache] Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000987fe70 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000980f698 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000097bb160 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009649778 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000969bf78 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009507c20 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000008d32388 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000008a51100 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000008841428 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000008703520 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000069da9d0 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000007756078 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000006e29298 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009b2ba70 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009a7e4d8 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000741fd28 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000007392720 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009a12878 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000997c3a0 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000991e1d8 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000098c7ba8 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000983ea60 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000097d7e00 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000973bf50 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000009628be0 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000095b30c0 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000008b8c998 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x0000000008af6678 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000088ea5a0 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x00000000087847d8 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem Debug: Facter: Searching fact: operatingsystem in file: operatingsystem.rb Debug: Facter: Searching fact: operatingsystem in core facts and external facts Debug: Facter: Loading all internal facts Debug: Facter: List of resolvable facts: [#<Facter::SearchedFact:0x000000000852d6b0 @name="operatingsystem", @fact_class=Facts::Windows::Os::Name, @user_query="operatingsystem", @type=:legacy, @file=nil>] Debug: Facter: Loading external facts Debug: Facter: fact "operatingsystem" has resolved to: windows Debug: Facter: resolving fact with user_query: operatingsystem

Add Comment

[Michiel van de Garde (Jira)]

Michiel van de Garde commented on FACT-3001

Re: Debug output shows facter gets called thousands of times

Seeing this issue in PE 2021.4 (agent 7.12.1/server 7.4.2/facter 4.2.5). Also seems to be confined to agent runs on windows. Logs show pretty much the same as those provided by kuc .

Add Comment

This message was sent by Atlassian Jira (v8.20.2#820002-sha1:829506d)

[Ciprian Badescu (Jira)]

Ciprian Badescu commented on FACT-3001

Re: Debug output shows facter gets called thousands of times

mvandegarde , can you provide us some logs where we can see the factor version in use the the issue? Thanks!

Add Comment

[Michiel van de Garde (Jira)]

Michiel van de Garde commented on FACT-3001

Re: Debug output shows facter gets called thousands of times

Hi ciprian.badescu , i've submitted a full agent log to puppet support in a support request (47112). I can't seem to reproduce the long runtimes with interactive runs started from a shell, but using the console to schedule a run with debug & trace enabled does reproduce the issue.

Add Comment

[Austin Boyd (Jira)]

Austin Boyd updated an issue

Facter / FACT-3001

Debug output shows facter gets called thousands of times

Change By: Austin Boyd Labels: doc-reviewed jira_escalated

Add Comment

[Austin Boyd (Jira)]

Austin Boyd updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Austin Boyd Zendesk Ticket Count: 1 Zendesk Ticket IDs: 47112

Add Comment

[Alvin Rodis (Jira)]

Alvin Rodis updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Alvin Rodis Zendesk Ticket Count: 1 2 Zendesk Ticket IDs: 47112 ,49141

Add Comment

This message was sent by Atlassian Jira (v8.20.11#820011-sha1:0629dd8)

[Nathanael Cole (Jira)]

Nathanael Cole updated an issue

Facter / FACT-3001 Debug output shows facter gets called thousands of times

Change By: Nathanael Cole Zendesk Ticket Count: 2 3 Zendesk Ticket IDs: 47112,49141 ,50054

Add Comment