Attachment 867146 Details for Bug 1211591

salt-minion logs

salt-minion.txt (text/plain), 207.72 KB, created by Richard Fan on 2023-05-22 11:14:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Richard Fan

Created: 2023-05-22 11:14:02 UTC

Size: 207.72 KB

patch

obsolete

>2023-05-19 04:40:54,386 [salt.utils.parsers:226 ][DEBUG   ][3004] Configuration file path: /etc/salt/minion
>2023-05-19 04:40:54,388 [salt.cli.daemons :281 ][INFO    ][3004] Setting up the Salt Minion "minion"
>2023-05-19 04:40:54,389 [salt.utils.process:250 ][INFO    ][3004] pidfile: /var/run/process_responsibility_salt-minion.pid not found
>2023-05-19 04:40:54,413 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'base64_encode' as a jinja filter
>2023-05-19 04:40:54,414 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'base64_decode' as a jinja filter
>2023-05-19 04:40:54,414 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'md5' as a jinja filter
>2023-05-19 04:40:54,414 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'sha1' as a jinja filter
>2023-05-19 04:40:54,414 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'sha256' as a jinja filter
>2023-05-19 04:40:54,415 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'sha512' as a jinja filter
>2023-05-19 04:40:54,415 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'hmac' as a jinja filter
>2023-05-19 04:40:54,415 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'hmac_compute' as a jinja filter
>2023-05-19 04:40:54,415 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'random_hash' as a jinja filter
>2023-05-19 04:40:54,416 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'rand_str' as a jinja filter
>2023-05-19 04:40:54,416 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'file_hashsum' as a jinja filter
>2023-05-19 04:40:54,764 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'http_query' as a jinja filter
>2023-05-19 04:40:54,768 [salt.utils.decorators.jinja:74  ][DEBUG   ][3004] Marking 'ifelse' as a jinja global
>2023-05-19 04:40:54,769 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'strftime' as a jinja filter
>2023-05-19 04:40:54,769 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'date_format' as a jinja filter
>2023-05-19 04:40:54,776 [salt.utils.decorators.jinja:74  ][DEBUG   ][3004] Marking 'raise' as a jinja global
>2023-05-19 04:40:54,776 [salt.utils.decorators.jinja:50  ][DEBUG   ][3004] Marking 'match' as a jinja test
>2023-05-19 04:40:54,776 [salt.utils.decorators.jinja:50  ][DEBUG   ][3004] Marking 'equalto' as a jinja test
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'skip' as a jinja filter
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'sequence' as a jinja filter
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'to_bool' as a jinja filter
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'indent' as a jinja filter
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'tojson' as a jinja filter
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'quote' as a jinja filter
>2023-05-19 04:40:54,777 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'regex_escape' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'regex_search' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'regex_match' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'regex_replace' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'uuid' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'unique' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'min' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'max' as a jinja filter
>2023-05-19 04:40:54,778 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'avg' as a jinja filter
>2023-05-19 04:40:54,779 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'union' as a jinja filter
>2023-05-19 04:40:54,779 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'intersect' as a jinja filter
>2023-05-19 04:40:54,779 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'difference' as a jinja filter
>2023-05-19 04:40:54,779 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'symmetric_difference' as a jinja filter
>2023-05-19 04:40:54,779 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'method_call' as a jinja filter
>2023-05-19 04:40:54,780 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'yaml_dquote' as a jinja filter
>2023-05-19 04:40:54,780 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'yaml_squote' as a jinja filter
>2023-05-19 04:40:54,780 [salt.utils.decorators.jinja:26  ][DEBUG   ][3004] Marking 'yaml_encode' as a jinja filter
>2023-05-19 04:40:54,790 [salt.utils.process:322 ][DEBUG   ][3004] Created pidfile: /var/run/salt-minion.pid
>2023-05-19 04:40:54,791 [salt.cli.daemons :83  ][INFO    ][3004] Starting up the Salt Minion
>2023-05-19 04:40:54,792 [salt.utils.event :1104][DEBUG   ][3004] AsyncEventPublisher PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:40:54,792 [salt.utils.event :1105][DEBUG   ][3004] AsyncEventPublisher PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:40:54,792 [salt.utils.event :1137][INFO    ][3004] Starting pull socket on /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:40:54,793 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:40:54,794 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:40:54,798 [salt.loader      :1101][DEBUG   ][3004] Grains refresh requested. Refreshing grains.
>2023-05-19 04:40:54,798 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion
>2023-05-19 04:40:54,949 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,950 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,952 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,956 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,957 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,960 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,967 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:40:54,975 [salt.loader.lazy :863 ][DEBUG   ][3004] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:40:54,976 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:40:55,196 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:40:55,197 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded zfs.is_supported
>2023-05-19 04:40:55,254 [salt.minion      :1290][INFO    ][3004] Creating minion process manager
>2023-05-19 04:40:55,256 [salt.utils.process:608 ][DEBUG   ][3004] Process Manager starting!
>2023-05-19 04:40:55,257 [salt.utils.process:608 ][DEBUG   ][3004] Process Manager starting!
>2023-05-19 04:40:55,301 [salt.minion      :790 ][DEBUG   ][3004] Connecting to master. Attempt 1 of 1
>2023-05-19 04:40:55,303 [salt.minion      :232 ][DEBUG   ][3004] Master URI: tcp://10.0.2.101:4506
>2023-05-19 04:40:55,304 [salt.crypt       :506 ][DEBUG   ][3004] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:40:55,306 [salt.crypt       :892 ][INFO    ][3004] Generating keys: /etc/salt/pki/minion
>2023-05-19 04:40:55,363 [salt.crypt       :208 ][DEBUG   ][3004] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:40:55,363 [salt.crypt       :188 ][DEBUG   ][3004] salt.crypt._get_key_with_evict: Loading private key
>2023-05-19 04:40:55,366 [salt.crypt       :900 ][DEBUG   ][3004] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:40:55,374 [salt.transport.zeromq:160 ][DEBUG   ][3004] Generated random reconnect delay between '1000ms' and '11000ms' (2730)
>2023-05-19 04:40:55,375 [salt.transport.zeromq:163 ][DEBUG   ][3004] Setting zmq_reconnect_ivl to '2730ms'
>2023-05-19 04:40:55,375 [salt.transport.zeromq:169 ][DEBUG   ][3004] Setting zmq_reconnect_ivl_max to '11000ms'
>2023-05-19 04:40:55,375 [salt.crypt       :208 ][DEBUG   ][3004] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:40:55,375 [salt.crypt       :900 ][DEBUG   ][3004] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:40:55,386 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:40:55,386 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:40:55,387 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:40:55,405 [salt.crypt       :851 ][ERROR   ][3004] The Salt Master has cached the public key for this node, this salt minion will wait for 10 seconds before attempting to re-authenticate
>2023-05-19 04:40:55,405 [salt.crypt       :676 ][INFO    ][3004] Waiting 10 seconds before retry.
>2023-05-19 04:41:05,407 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:05,420 [salt.crypt       :851 ][ERROR   ][3004] The Salt Master has cached the public key for this node, this salt minion will wait for 10 seconds before attempting to re-authenticate
>2023-05-19 04:41:05,421 [salt.crypt       :676 ][INFO    ][3004] Waiting 10 seconds before retry.
>2023-05-19 04:41:15,427 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:15,441 [salt.crypt       :851 ][ERROR   ][3004] The Salt Master has cached the public key for this node, this salt minion will wait for 10 seconds before attempting to re-authenticate
>2023-05-19 04:41:15,442 [salt.crypt       :676 ][INFO    ][3004] Waiting 10 seconds before retry.
>2023-05-19 04:41:25,448 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:25,469 [salt.crypt       :851 ][ERROR   ][3004] The Salt Master has cached the public key for this node, this salt minion will wait for 10 seconds before attempting to re-authenticate
>2023-05-19 04:41:25,469 [salt.crypt       :676 ][INFO    ][3004] Waiting 10 seconds before retry.
>2023-05-19 04:41:35,475 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:35,495 [salt.crypt       :974 ][DEBUG   ][3004] Decrypting the current master AES key
>2023-05-19 04:41:35,496 [salt.crypt       :208 ][DEBUG   ][3004] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:35,496 [salt.crypt       :900 ][DEBUG   ][3004] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:35,503 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:35,504 [salt.crypt       :255 ][DEBUG   ][3004] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:41:35,504 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:35,504 [salt.crypt       :257 ][DEBUG   ][3004] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:41:35,504 [salt.crypt       :701 ][DEBUG   ][3004] <salt.crypt.AsyncAuth object at 0x7f4ac4098c18> Got new master aes key.
>2023-05-19 04:41:35,505 [salt.channel.client:313 ][DEBUG   ][3004] Closing AsyncReqChannel instance
>2023-05-19 04:41:35,506 [salt.transport.zeromq:211 ][DEBUG   ][3004] Connecting the Minion to the Master publish port, using the URI: tcp://10.0.2.101:4505
>2023-05-19 04:41:35,506 [salt.transport.zeromq:213 ][DEBUG   ][3004] <salt.transport.zeromq.PublishClient object at 0x7f4ace64bcf8> connecting to tcp://10.0.2.101:4505
>2023-05-19 04:41:35,509 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = __master_connected; data = {'master': '10.0.2.101', '_stamp': '2023-05-19T02:41:35.509270'}
>2023-05-19 04:41:35,510 [salt.crypt       :208 ][DEBUG   ][3004] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:35,511 [salt.crypt       :900 ][DEBUG   ][3004] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:35,518 [salt.crypt       :514 ][DEBUG   ][3004] Re-using AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:35,518 [salt.minion      :1377][DEBUG   ][3004] Connecting minion's long-running req channel
>2023-05-19 04:41:35,519 [salt.crypt       :514 ][DEBUG   ][3004] Re-using AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:35,635 [salt.crypt       :208 ][DEBUG   ][3004] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:35,635 [salt.crypt       :900 ][DEBUG   ][3004] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:35,642 [salt.crypt       :255 ][DEBUG   ][3004] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:41:35,643 [salt.crypt       :216 ][DEBUG   ][3004] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:35,643 [salt.crypt       :257 ][DEBUG   ][3004] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:41:35,643 [salt.channel.client:313 ][DEBUG   ][3004] Closing AsyncReqChannel instance
>2023-05-19 04:41:35,644 [salt.loader      :1101][DEBUG   ][3004] Grains refresh requested. Refreshing grains.
>2023-05-19 04:41:35,644 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion
>2023-05-19 04:41:35,760 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,760 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,761 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,763 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,764 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,766 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,768 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,770 [salt.loader.lazy :863 ][DEBUG   ][3004] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:41:35,771 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,963 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:35,964 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded zfs.is_supported
>2023-05-19 04:41:36,055 [salt.utils.schedule:94  ][DEBUG   ][3004] Initializing new Schedule
>2023-05-19 04:41:36,071 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'timezone' are being loaded by dir() on the loaded module
>2023-05-19 04:41:36,071 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded timezone.get_offset
>2023-05-19 04:41:36,072 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'cmd' are being loaded by dir() on the loaded module
>2023-05-19 04:41:36,073 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded cmd.run
>2023-05-19 04:41:36,073 [salt.loader.10.0.2.101.int.module.cmdmod:438 ][INFO    ][3004] Executing command date in directory '/root'
>2023-05-19 04:41:36,079 [salt.loader.10.0.2.101.int.module.cmdmod:922 ][DEBUG   ][3004] stdout: +0200
>2023-05-19 04:41:36,079 [salt.loader.10.0.2.101.int.module.cmdmod:1352][DEBUG   ][3004] output: +0200
>2023-05-19 04:41:36,244 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:36,245 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded config.merge
>2023-05-19 04:41:36,247 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:36,247 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:36,248 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = /salt/minion/minion_schedule_delete_complete; data = {'complete': True, 'schedule': {}, '_stamp': '2023-05-19T02:41:36.248473'}
>2023-05-19 04:41:36,249 [salt.transport.ipc:373 ][DEBUG   ][3004] Closing IPCMessageClient instance
>2023-05-19 04:41:36,249 [salt.utils.schedule:303 ][DEBUG   ][3004] Persisting schedule
>2023-05-19 04:41:36,251 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:41:36,252 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded mine.update
>2023-05-19 04:41:36,252 [salt.utils.schedule:409 ][INFO    ][3004] Added new job __mine_interval to scheduler
>2023-05-19 04:41:36,252 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:36,253 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:36,255 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = /salt/minion/minion_schedule_add_complete; data = {'complete': True, 'schedule': {'__mine_interval': {'function': 'mine.update', 'minutes': 60, 'jid_include': True, 'maxrunning': 2, 'run_on_start': True, 'return_job': False, 'enabled': True}}, '_stamp': '2023-05-19T02:41:36.255080'}
>2023-05-19 04:41:36,255 [salt.transport.ipc:373 ][DEBUG   ][3004] Closing IPCMessageClient instance
>2023-05-19 04:41:36,256 [salt.utils.schedule:303 ][DEBUG   ][3004] Persisting schedule
>2023-05-19 04:41:36,295 [salt.minion      :1456][INFO    ][3004] Added mine.update to scheduler
>2023-05-19 04:41:36,296 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:36,296 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:36,298 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = /salt/minion/minion_schedule_delete_complete; data = {'complete': True, 'schedule': {'__mine_interval': {'function': 'mine.update', 'minutes': 60, 'jid_include': True, 'maxrunning': 2, 'run_on_start': True, 'return_job': False, 'enabled': True}}, '_stamp': '2023-05-19T02:41:36.298001'}
>2023-05-19 04:41:36,300 [salt.transport.ipc:373 ][DEBUG   ][3004] Closing IPCMessageClient instance
>2023-05-19 04:41:36,301 [salt.utils.schedule:303 ][DEBUG   ][3004] Persisting schedule
>2023-05-19 04:41:36,303 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:36,303 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:36,304 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = /salt/minion/minion_schedule_delete_complete; data = {'complete': True, 'schedule': {'__mine_interval': {'function': 'mine.update', 'minutes': 60, 'jid_include': True, 'maxrunning': 2, 'run_on_start': True, 'return_job': False, 'enabled': True}}, '_stamp': '2023-05-19T02:41:36.304078'}
>2023-05-19 04:41:36,304 [salt.transport.ipc:373 ][DEBUG   ][3004] Closing IPCMessageClient instance
>2023-05-19 04:41:36,304 [salt.utils.schedule:303 ][DEBUG   ][3004] Persisting schedule
>2023-05-19 04:41:36,308 [salt.minion      :2649][INFO    ][3004] Minion is starting as user 'root'
>2023-05-19 04:41:36,308 [salt.minion      :3090][DEBUG   ][3004] Minion 'minion' trying to tune in
>2023-05-19 04:41:36,309 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:36,309 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:36,311 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = __master_req_channel_payload; data = {'id': 'minion', 'cmd': '_minion_event', 'pretag': None, 'tok': b'h2\x03),\xe9\xfdBX\x0br\xb8\xc3:;\xde\xb3R\xe9\x84\xd4L\xef\xed\xaad^\x90\xfc\xd1aS\xfcf\x8bvvd)\x91\x0b\xb3\xdf\xec=\xd1\x13\xe2\x02\x934\xc1\x90\x01?Q^)\xce\xa7\x07\xcc2\xce2\x95\xbc\xb0\xd5\xba\x0e\x14\x8a\xda\xc7\xadR\x916\x7fA\xeb\xdb\xdcwcr\x03x\xeb\xb5\xe9\x1br\xb6\xb0\x94\xc3\xc23\xe0\t4\xfdb\xd9:\x7f%\xa56\xbd\xe8*\xa5\x7f\x8aE\x85r\x90\x1a\x95\xca\xc9^\x9c#\xb5\xd3#\x8e_\xb5\xbd\xa0p\xa2\xc1\x92{\xde\xa4J\x14\x93~\xfb\xda\xe8\xaf&w\\\x19\x06\xfb9\xf0K\x94q\xd3s\xb3\xc7\x0eQ\x02\x06\t\xfbj\xa9i#>m\xb4\x18]\x89\xa9\xdc\x9e\x83cip9\xdew\xaa\xf4FK\xfd\x9e\\z8M\x0e+\x9c\x82,A7\xf1\x9fYV\xc7\xcb\x0b5(\x15\x92\xb50)\xf5\x96\x8c\x97r\xcb\xad\xe0\xfd\xdag\xfcU\x81\xfa\xc9J\xb8F\xb4\xcc\x84W]\xb5\xb5\xc4\xf7\x1c\xc1\xf5\xe8_', 'data': 'Minion minion started at Fri May 19 04:41:36 2023', 'tag': 'minion_start', '_stamp': '2023-05-19T02:41:36.310993'}
>2023-05-19 04:41:36,311 [salt.transport.ipc:373 ][DEBUG   ][3004] Closing IPCMessageClient instance
>2023-05-19 04:41:36,312 [salt.utils.event :315 ][DEBUG   ][3004] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:36,312 [salt.utils.event :316 ][DEBUG   ][3004] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:36,313 [salt.utils.event :823 ][DEBUG   ][3004] Sending event: tag = __master_req_channel_payload; data = {'id': 'minion', 'cmd': '_minion_event', 'pretag': None, 'tok': b'h2\x03),\xe9\xfdBX\x0br\xb8\xc3:;\xde\xb3R\xe9\x84\xd4L\xef\xed\xaad^\x90\xfc\xd1aS\xfcf\x8bvvd)\x91\x0b\xb3\xdf\xec=\xd1\x13\xe2\x02\x934\xc1\x90\x01?Q^)\xce\xa7\x07\xcc2\xce2\x95\xbc\xb0\xd5\xba\x0e\x14\x8a\xda\xc7\xadR\x916\x7fA\xeb\xdb\xdcwcr\x03x\xeb\xb5\xe9\x1br\xb6\xb0\x94\xc3\xc23\xe0\t4\xfdb\xd9:\x7f%\xa56\xbd\xe8*\xa5\x7f\x8aE\x85r\x90\x1a\x95\xca\xc9^\x9c#\xb5\xd3#\x8e_\xb5\xbd\xa0p\xa2\xc1\x92{\xde\xa4J\x14\x93~\xfb\xda\xe8\xaf&w\\\x19\x06\xfb9\xf0K\x94q\xd3s\xb3\xc7\x0eQ\x02\x06\t\xfbj\xa9i#>m\xb4\x18]\x89\xa9\xdc\x9e\x83cip9\xdew\xaa\xf4FK\xfd\x9e\\z8M\x0e+\x9c\x82,A7\xf1\x9fYV\xc7\xcb\x0b5(\x15\x92\xb50)\xf5\x96\x8c\x97r\xcb\xad\xe0\xfd\xdag\xfcU\x81\xfa\xc9J\xb8F\xb4\xcc\x84W]\xb5\xb5\xc4\xf7\x1c\xc1\xf5\xe8_', 'data': 'Minion minion started at Fri May 19 04:41:36 2023', 'tag': 'salt/minion/minion/start', '_stamp': '2023-05-19T02:41:36.313709'}
>2023-05-19 04:41:36,314 [salt.transport.ipc:373 ][DEBUG   ][3004] Closing IPCMessageClient instance
>2023-05-19 04:41:36,314 [salt.minion      :3100][INFO    ][3004] Minion is ready to receive requests!
>2023-05-19 04:41:36,347 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '/salt/minion/minion_schedule_delete_complete'
>2023-05-19 04:41:36,348 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '/salt/minion/minion_schedule_add_complete'
>2023-05-19 04:41:36,348 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '/salt/minion/minion_schedule_delete_complete'
>2023-05-19 04:41:36,348 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '/salt/minion/minion_schedule_delete_complete'
>2023-05-19 04:41:36,348 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:41:36,349 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 5 seconds (randomized)
>2023-05-19 04:41:36,350 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:41:36,351 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 5 seconds (randomized)
>2023-05-19 04:41:36,352 [tornado.general  :444 ][WARNING ][3004] Got events for closed stream None
>2023-05-19 04:41:37,346 [salt.utils.schedule:1727][DEBUG   ][3004] schedule: Job __mine_interval was scheduled with jid_include, adding to cache (jid_include defaults to True)
>2023-05-19 04:41:37,346 [salt.utils.schedule:1734][DEBUG   ][3004] schedule: Job __mine_interval was scheduled with a max number of 2
>2023-05-19 04:41:37,347 [salt.utils.schedule:1758][INFO    ][3004] Running scheduled job: __mine_interval with jid 20230519024137347220
>2023-05-19 04:41:37,350 [salt.utils.process:1145][DEBUG   ][3004] Subprocess Schedule(name=__mine_interval, jid=20230519024137347220) added
>2023-05-19 04:41:37,441 [salt.loader.lazy :967 ][DEBUG   ][3073] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:41:37,441 [salt.utils.lazy  :99  ][DEBUG   ][3073] LazyLoaded mine.update
>2023-05-19 04:41:37,441 [salt.utils.schedule:786 ][DEBUG   ][3073] schedule.handle_func: adding this job to the jobcache with data {'id': 'minion', 'fun': 'mine.update', 'fun_args': [], 'schedule': '__mine_interval', 'jid': '20230519024137347220', 'pid': 3073}
>2023-05-19 04:41:37,444 [salt.loader.lazy :967 ][DEBUG   ][3073] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:37,444 [salt.utils.lazy  :99  ][DEBUG   ][3073] LazyLoaded config.merge
>2023-05-19 04:41:37,445 [salt.utils.schedule:925 ][DEBUG   ][3073] schedule.handle_func: Removing /var/cache/salt/minion/proc/20230519024137347220
>2023-05-19 04:41:38,347 [salt.utils.process:1156][DEBUG   ][3004] Subprocess Schedule(name=__mine_interval, jid=20230519024137347220) cleaned up
>2023-05-19 04:41:40,705 [salt.channel.client:427 ][DEBUG   ][3004] PubChannel received: {'enc': 'aes', 'load': {'fun': 'test.ping', 'arg': [], 'tgt': '*', 'jid': '20230519024140683796', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}, 'sig': b'/E\xb0v\xd3\xda\xde\xd3\xd8\x8c\xed\x11\xed\x19\x80\xff%\xe6\x9f\xe4\xba(\x90\t\x16\xa5\xf2\x04\xc6\xfe\xa9#\x10[\x16\xa7\xb0N\x19\r\xd7\x9c\xda0)\xffi\xfb\xc5\\\xda\xac\xb4?\n\xb8\x1a\xd1v0\xcf\x9b\x89\xe6\xd3\x18>\xd6w-\xf4K\xb3:\x0f[B|!u\x87\x13Z\xfbj\x91\x10m\x84\x150S\xd2|o\xa3\xff\xb5\x01\x1cSG\x8f\x9a#\x07\xcb\x14\xefN\xef\xcfP\xf5\x1bL\xea\x13^\x94zDi\x83Z\xfbh\xc5\xc0\xed[\x9eY\xbe\xde9+\xa4J\xde\xca\xf7\xf4Q\xb3\x02\x1a\xa8\xa0\x8dj\x1a\x7f\xd0^\xc8;\xb0D\xc4\xe1\xd0\xdc\x9e\xfc\x87\x8a\xfe\x95~\xdf\xbe\x87!\x0c\xe8\x90\xe7\x9f\x0fy7\x1eB\xd3\x93T\xab\xd3CL\xc5\x1a\xc4\x1d\xe6J\x96\xae\xdc\x9c\xc7\x9f\x8a\x93\x10\xe8\xaa<\xccj\xae\x1d\xf5\x02\x8d\xc7\x89\xf0-\xa4{T\x94\xf5e\x17[\xc3#\x9b\x88\x0eT\xee\x17t{^]$\xf3-\xfbh2\xfeDS\nU33\xab\x00\x12'}
>2023-05-19 04:41:40,708 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'glob_match' are being loaded by dir() on the loaded module
>2023-05-19 04:41:40,708 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded glob_match.match
>2023-05-19 04:41:40,709 [salt.minion      :1717][INFO    ][3004] User root Executing command test.ping with jid 20230519024140683796
>2023-05-19 04:41:40,709 [salt.minion      :1721][DEBUG   ][3004] Command details {'fun': 'test.ping', 'arg': [], 'tgt': '*', 'jid': '20230519024140683796', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}
>2023-05-19 04:41:40,716 [salt.utils.process:1145][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024140683796) added
>2023-05-19 04:41:40,909 [salt.loader.lazy :967 ][DEBUG   ][3074] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:40,909 [salt.utils.lazy  :99  ][DEBUG   ][3074] LazyLoaded jinja.render
>2023-05-19 04:41:40,911 [salt.loader.lazy :967 ][DEBUG   ][3074] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:40,911 [salt.utils.lazy  :99  ][DEBUG   ][3074] LazyLoaded yaml.render
>2023-05-19 04:41:40,916 [salt.minion      :1917][INFO    ][3074] Starting a new job 20230519024140683796 with PID 3074
>2023-05-19 04:41:40,917 [salt.loader.lazy :967 ][DEBUG   ][3074] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:41:40,918 [salt.loader.lazy :967 ][DEBUG   ][3074] The functions from module 'splay' are being loaded by dir() on the loaded module
>2023-05-19 04:41:40,921 [salt.utils.lazy  :103 ][DEBUG   ][3074] Could not LazyLoad direct_call.allow_missing_func: 'direct_call.allow_missing_func' is not available.
>2023-05-19 04:41:40,922 [salt.loader.lazy :967 ][DEBUG   ][3074] The functions from module 'test' are being loaded by dir() on the loaded module
>2023-05-19 04:41:40,923 [salt.utils.lazy  :99  ][DEBUG   ][3074] LazyLoaded test.ping
>2023-05-19 04:41:40,924 [salt.loaded.int.module.test:121 ][DEBUG   ][3074] test.ping received for minion 'minion'
>2023-05-19 04:41:40,924 [salt.minion      :2196][INFO    ][3074] Returning information for job: 20230519024140683796
>2023-05-19 04:41:40,926 [salt.utils.event :315 ][DEBUG   ][3074] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:40,926 [salt.utils.event :316 ][DEBUG   ][3074] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:40,930 [salt.utils.event :823 ][DEBUG   ][3074] Sending event: tag = __master_req_channel_payload; data = {'cmd': '_return', 'id': 'minion', 'success': True, 'return': True, 'retcode': 0, 'jid': '20230519024140683796', 'fun': 'test.ping', 'fun_args': [], '_stamp': '2023-05-19T02:41:40.930436'}
>2023-05-19 04:41:40,932 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:41:40,932 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 6 seconds (randomized)
>2023-05-19 04:41:40,935 [salt.transport.ipc:373 ][DEBUG   ][3074] Closing IPCMessageClient instance
>2023-05-19 04:41:40,937 [salt.minion      :2065][DEBUG   ][3074] minion return: {'success': True, 'return': True, 'retcode': 0, 'jid': '20230519024140683796', 'fun': 'test.ping', 'fun_args': []}
>2023-05-19 04:41:41,346 [salt.utils.process:1156][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024140683796) cleaned up
>2023-05-19 04:41:44,577 [salt.channel.client:427 ][DEBUG   ][3004] PubChannel received: {'enc': 'aes', 'load': {'fun': 'test.ping', 'arg': [], 'tgt': '*', 'jid': '20230519024144562356', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}, 'sig': b"8\xd0Z\x8b\x87\xa0^\xee\\*w\xdd\xcc\xdc\xfc~\xa89\xc3\xaf\x99W\x02\x0bmLl_=\x7f.\x8cT\xa8\xaf\x83e\xf6X\xc0;\xf6\x89\xa0U\x85I\x15.\xeb\x91>\x92\x9a0\x19\xeb\x80\xcb6\xdd\x9b\xb2W*\xed\xff\xa8\x12`rQ\x94\xad\xccP8\xa3\xcb}'K\x87T\xb7;\x82|\x06B\x93U\xf3.\xba\xe7\xb2Z\xcd\xda6(\x87\xf9Y\x99L\xa6\x17\xd3\xcb\xe1N\x92\x82\x95\xf1\xf4@\x10\xa8\xdc\t\xd1\x1c+\x82:LU\xde\xda\xb9\xdd]O\xf5\xa6)D\xe7\xa02\xceZ\xf6%B'f\x1d\x1e\xe7\x8b\xfcd:\xd2\xfc?\xc7\x99NI\x91P\x8f\xa7\xb7N\xde|n\xfc\xc7\xd5{\xd3\xa4+Z)2\x1e\xd5\xb9\xfa?\xd0\xcb\xf2\x13\xae\xae\xdb\xeb,\xc3[FS\xe1\x9e<&|=(\xd8g\x0e4\xd3\xb1f\xa8\x89\x88\x83\xe3 3\xe9C\x8a\xc2n\xcb\xf9\xf3\xd4\xd4H\xc3U\xdd\x7f\xb8\x82q\xd2\xb2O7\xb6@\xde\xbad~e\x1a\x8az\x18\x11"}
>2023-05-19 04:41:44,578 [salt.minion      :1717][INFO    ][3004] User root Executing command test.ping with jid 20230519024144562356
>2023-05-19 04:41:44,578 [salt.minion      :1721][DEBUG   ][3004] Command details {'fun': 'test.ping', 'arg': [], 'tgt': '*', 'jid': '20230519024144562356', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}
>2023-05-19 04:41:44,585 [salt.utils.process:1145][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024144562356) added
>2023-05-19 04:41:44,725 [salt.loader.lazy :967 ][DEBUG   ][3077] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:44,726 [salt.utils.lazy  :99  ][DEBUG   ][3077] LazyLoaded jinja.render
>2023-05-19 04:41:44,727 [salt.loader.lazy :967 ][DEBUG   ][3077] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:44,728 [salt.utils.lazy  :99  ][DEBUG   ][3077] LazyLoaded yaml.render
>2023-05-19 04:41:44,732 [salt.minion      :1917][INFO    ][3077] Starting a new job 20230519024144562356 with PID 3077
>2023-05-19 04:41:44,733 [salt.loader.lazy :967 ][DEBUG   ][3077] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:41:44,734 [salt.loader.lazy :967 ][DEBUG   ][3077] The functions from module 'splay' are being loaded by dir() on the loaded module
>2023-05-19 04:41:44,737 [salt.utils.lazy  :103 ][DEBUG   ][3077] Could not LazyLoad direct_call.allow_missing_func: 'direct_call.allow_missing_func' is not available.
>2023-05-19 04:41:44,739 [salt.loader.lazy :967 ][DEBUG   ][3077] The functions from module 'test' are being loaded by dir() on the loaded module
>2023-05-19 04:41:44,739 [salt.utils.lazy  :99  ][DEBUG   ][3077] LazyLoaded test.ping
>2023-05-19 04:41:44,740 [salt.loaded.int.module.test:121 ][DEBUG   ][3077] test.ping received for minion 'minion'
>2023-05-19 04:41:44,741 [salt.minion      :2196][INFO    ][3077] Returning information for job: 20230519024144562356
>2023-05-19 04:41:44,742 [salt.utils.event :315 ][DEBUG   ][3077] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:44,742 [salt.utils.event :316 ][DEBUG   ][3077] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:44,746 [salt.utils.event :823 ][DEBUG   ][3077] Sending event: tag = __master_req_channel_payload; data = {'cmd': '_return', 'id': 'minion', 'success': True, 'return': True, 'retcode': 0, 'jid': '20230519024144562356', 'fun': 'test.ping', 'fun_args': [], '_stamp': '2023-05-19T02:41:44.746107'}
>2023-05-19 04:41:44,747 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:41:44,748 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 7 seconds (randomized)
>2023-05-19 04:41:44,751 [salt.transport.ipc:373 ][DEBUG   ][3077] Closing IPCMessageClient instance
>2023-05-19 04:41:44,752 [salt.minion      :2065][DEBUG   ][3077] minion return: {'success': True, 'return': True, 'retcode': 0, 'jid': '20230519024144562356', 'fun': 'test.ping', 'fun_args': []}
>2023-05-19 04:41:45,349 [salt.utils.process:1156][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024144562356) cleaned up
>2023-05-19 04:41:45,807 [salt.channel.client:427 ][DEBUG   ][3004] PubChannel received: {'enc': 'aes', 'load': {'fun': 'cmd.run', 'arg': ['touch /tmp/salt_touch'], 'tgt': '*', 'jid': '20230519024145801836', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}, 'sig': b'JQ|\xa3:\xa8\xb0j\x8fk/\xb8\xa4\\\xe39\xe9 uevT\xc0\xca\xaa_n~\xb5\x01\xeb\xda\x05\x13\xbb\x9d\x86g\xb9\xb1\xb3\x9b\xfdA\xae\xf3\xb8\r\xc8\x0e\xa6H\xf1A\xd3\xe8Jc\x03\xe5\x85\xb0\x17,\xa6\xbe \xe6\x84E\x91\xb7\x19T\x80\xcbEx\xa0\x16\x9d\x1e\xb6.\xc9\x86\x80,\xdf\x9f\xbdOOF\x06q\xfbI\xcd\xa8\xbdEo\xd2\xf9\xb8\xb1\xcc<\x7f\x910\xd0K`\xce:\xc3\xbb\xb8\xfd\xa2$\xdf\x84(\xd7\xbbm!\xf3s\xc4\xfa\xfc\x8ft\x9d\xf9U\xb5\xc6\x02\x19\t\xe2\xcc[\xafQ\x94K\\\x08\xfb;\xd4B\x94\x1dqR|I\x877X#o\xc8\x9ewgf\x9c\x8e(zi\xe0C\x00\xea\x89M\x13\xc1\x80\x00\xd9\xd1\xe8\x10\tB3\x18\xc4_\xcf\xfe\x94\x8d\xd2\xf2\xa7O\xc05\xe0-\xa0O\r+/egez\x0f\x08\xb9\xbe2\xc4\xab\r\xe7O\x85i/\xb2\xc17an\x8d\x9b\x17\xea,\xef\x98d\x9aR\xf4\x9br\xc2\xd3\x04A\x02'}
>2023-05-19 04:41:45,808 [salt.minion      :1717][INFO    ][3004] User root Executing command cmd.run with jid 20230519024145801836
>2023-05-19 04:41:45,808 [salt.minion      :1721][DEBUG   ][3004] Command details {'fun': 'cmd.run', 'arg': ['touch /tmp/salt_touch'], 'tgt': '*', 'jid': '20230519024145801836', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}
>2023-05-19 04:41:45,813 [salt.utils.process:1145][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024145801836) added
>2023-05-19 04:41:45,931 [salt.loader.lazy :967 ][DEBUG   ][3080] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:45,931 [salt.utils.lazy  :99  ][DEBUG   ][3080] LazyLoaded jinja.render
>2023-05-19 04:41:45,933 [salt.loader.lazy :967 ][DEBUG   ][3080] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:45,933 [salt.utils.lazy  :99  ][DEBUG   ][3080] LazyLoaded yaml.render
>2023-05-19 04:41:45,937 [salt.minion      :1917][INFO    ][3080] Starting a new job 20230519024145801836 with PID 3080
>2023-05-19 04:41:45,937 [salt.loader.lazy :967 ][DEBUG   ][3080] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:41:45,939 [salt.loader.lazy :967 ][DEBUG   ][3080] The functions from module 'splay' are being loaded by dir() on the loaded module
>2023-05-19 04:41:45,941 [salt.utils.lazy  :103 ][DEBUG   ][3080] Could not LazyLoad direct_call.allow_missing_func: 'direct_call.allow_missing_func' is not available.
>2023-05-19 04:41:45,943 [salt.loader.lazy :967 ][DEBUG   ][3080] The functions from module 'cmd' are being loaded by dir() on the loaded module
>2023-05-19 04:41:45,943 [salt.utils.lazy  :99  ][DEBUG   ][3080] LazyLoaded cmd.run
>2023-05-19 04:41:45,946 [salt.loader.lazy :967 ][DEBUG   ][3080] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:45,946 [salt.utils.lazy  :99  ][DEBUG   ][3080] LazyLoaded config.get
>2023-05-19 04:41:45,948 [salt.loaded.int.module.cmdmod:438 ][INFO    ][3080] Executing command 'touch' in directory '/root'
>2023-05-19 04:41:45,954 [salt.loaded.int.module.cmdmod:1352][DEBUG   ][3080] output: 
>2023-05-19 04:41:45,955 [salt.minion      :2196][INFO    ][3080] Returning information for job: 20230519024145801836
>2023-05-19 04:41:45,957 [salt.utils.event :315 ][DEBUG   ][3080] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:45,957 [salt.utils.event :316 ][DEBUG   ][3080] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:45,961 [salt.utils.event :823 ][DEBUG   ][3080] Sending event: tag = __master_req_channel_payload; data = {'cmd': '_return', 'id': 'minion', 'success': True, 'return': '', 'retcode': 0, 'jid': '20230519024145801836', 'fun': 'cmd.run', 'fun_args': ['touch /tmp/salt_touch'], '_stamp': '2023-05-19T02:41:45.960930'}
>2023-05-19 04:41:45,962 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:41:45,962 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 9 seconds (randomized)
>2023-05-19 04:41:45,964 [salt.transport.ipc:373 ][DEBUG   ][3080] Closing IPCMessageClient instance
>2023-05-19 04:41:45,966 [salt.minion      :2065][DEBUG   ][3080] minion return: {'success': True, 'return': '', 'retcode': 0, 'jid': '20230519024145801836', 'fun': 'cmd.run', 'fun_args': ['touch /tmp/salt_touch']}
>2023-05-19 04:41:46,347 [salt.utils.process:1156][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024145801836) cleaned up
>2023-05-19 04:41:49,975 [salt.utils.parsers:226 ][DEBUG   ][3084] Configuration file path: /etc/salt/minion
>2023-05-19 04:41:49,975 [salt.loader      :1101][DEBUG   ][3084] Grains refresh requested. Refreshing grains.
>2023-05-19 04:41:49,975 [salt.config      :2017][DEBUG   ][3084] Reading configuration from /etc/salt/minion
>2023-05-19 04:41:50,037 [salt.config      :2180][DEBUG   ][3084] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:41:50,038 [salt.config      :2017][DEBUG   ][3084] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:41:50,076 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,077 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,078 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,081 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,082 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,084 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,089 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,097 [salt.loader.lazy :863 ][DEBUG   ][3084] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:41:50,097 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,276 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,276 [salt.utils.lazy  :99  ][DEBUG   ][3084] LazyLoaded zfs.is_supported
>2023-05-19 04:41:50,322 [salt.minion      :790 ][DEBUG   ][3084] Connecting to master. Attempt 1 of 1
>2023-05-19 04:41:50,323 [salt.minion      :232 ][DEBUG   ][3084] Master URI: tcp://10.0.2.101:4506
>2023-05-19 04:41:50,323 [salt.crypt       :506 ][DEBUG   ][3084] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:50,331 [salt.transport.zeromq:160 ][DEBUG   ][3084] Generated random reconnect delay between '1000ms' and '11000ms' (8418)
>2023-05-19 04:41:50,331 [salt.transport.zeromq:163 ][DEBUG   ][3084] Setting zmq_reconnect_ivl to '8418ms'
>2023-05-19 04:41:50,331 [salt.transport.zeromq:169 ][DEBUG   ][3084] Setting zmq_reconnect_ivl_max to '11000ms'
>2023-05-19 04:41:50,331 [salt.crypt       :208 ][DEBUG   ][3084] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:50,331 [salt.crypt       :188 ][DEBUG   ][3084] salt.crypt._get_key_with_evict: Loading private key
>2023-05-19 04:41:50,334 [salt.crypt       :900 ][DEBUG   ][3084] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:50,342 [salt.utils.event :315 ][DEBUG   ][3084] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:50,342 [salt.utils.event :316 ][DEBUG   ][3084] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:50,343 [salt.crypt       :216 ][DEBUG   ][3084] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:50,369 [salt.crypt       :974 ][DEBUG   ][3084] Decrypting the current master AES key
>2023-05-19 04:41:50,369 [salt.crypt       :208 ][DEBUG   ][3084] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:50,370 [salt.crypt       :900 ][DEBUG   ][3084] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:50,377 [salt.crypt       :216 ][DEBUG   ][3084] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:50,384 [salt.crypt       :255 ][DEBUG   ][3084] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:41:50,384 [salt.crypt       :216 ][DEBUG   ][3084] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:50,384 [salt.crypt       :257 ][DEBUG   ][3084] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:41:50,385 [salt.crypt       :701 ][DEBUG   ][3084] <salt.crypt.AsyncAuth object at 0x7fe8572d2cf8> Got new master aes key.
>2023-05-19 04:41:50,386 [salt.channel.client:313 ][DEBUG   ][3084] Closing AsyncReqChannel instance
>2023-05-19 04:41:50,387 [salt.transport.zeromq:211 ][DEBUG   ][3084] Connecting the Minion to the Master publish port, using the URI: tcp://10.0.2.101:4505
>2023-05-19 04:41:50,387 [salt.transport.zeromq:213 ][DEBUG   ][3084] <salt.transport.zeromq.PublishClient object at 0x7fe8572d2a58> connecting to tcp://10.0.2.101:4505
>2023-05-19 04:41:50,390 [salt.utils.event :823 ][DEBUG   ][3084] Sending event: tag = __master_connected; data = {'master': '10.0.2.101', '_stamp': '2023-05-19T02:41:50.390632'}
>2023-05-19 04:41:50,391 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_connected'
>2023-05-19 04:41:50,392 [salt.crypt       :208 ][DEBUG   ][3084] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:50,392 [salt.crypt       :900 ][DEBUG   ][3084] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:50,399 [salt.transport.ipc:373 ][DEBUG   ][3084] Closing IPCMessageClient instance
>2023-05-19 04:41:50,400 [salt.pillar      :71  ][DEBUG   ][3084] Determining pillar cache
>2023-05-19 04:41:50,400 [salt.crypt       :506 ][DEBUG   ][3084] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:50,500 [salt.crypt       :208 ][DEBUG   ][3084] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:50,501 [salt.crypt       :900 ][DEBUG   ][3084] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:50,514 [salt.crypt       :255 ][DEBUG   ][3084] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:41:50,514 [salt.crypt       :216 ][DEBUG   ][3084] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:50,514 [salt.crypt       :257 ][DEBUG   ][3084] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:41:50,515 [salt.channel.client:313 ][DEBUG   ][3084] Closing AsyncReqChannel instance
>2023-05-19 04:41:50,601 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,601 [salt.utils.lazy  :99  ][DEBUG   ][3084] LazyLoaded jinja.render
>2023-05-19 04:41:50,602 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,602 [salt.utils.lazy  :99  ][DEBUG   ][3084] LazyLoaded yaml.render
>2023-05-19 04:41:50,607 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'test' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,607 [salt.utils.lazy  :99  ][DEBUG   ][3084] LazyLoaded test.ping
>2023-05-19 04:41:50,609 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,609 [salt.utils.lazy  :99  ][DEBUG   ][3084] LazyLoaded direct_call.execute
>2023-05-19 04:41:50,610 [salt.loaded.int.module.test:121 ][DEBUG   ][3084] test.ping received for minion 'minion'
>2023-05-19 04:41:50,610 [salt.crypt       :506 ][DEBUG   ][3084] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:50,619 [salt.channel.client:313 ][DEBUG   ][3084] Closing AsyncReqChannel instance
>2023-05-19 04:41:50,624 [salt.loader.lazy :967 ][DEBUG   ][3084] The functions from module 'nested' are being loaded by dir() on the loaded module
>2023-05-19 04:41:50,624 [salt.utils.lazy  :99  ][DEBUG   ][3084] LazyLoaded nested.output
>2023-05-19 04:41:51,667 [salt.channel.client:427 ][DEBUG   ][3004] PubChannel received: {'enc': 'aes', 'load': {'fun': 'state.highstate', 'arg': [], 'tgt': '*', 'jid': '20230519024151658886', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}, 'sig': b'$/K\xf8\xaas\xdb\xb6Iz9\x14\xf7\xa8\x03\xed!\xda\xdc\x14\xcb\xa9\xd8\xe2To\x00\x8b\x9e\xbdE\x80>\xaeo@>\r\xddMz\xe8NS\xcc\xbc*<>\xbe\xa1\xa03\x1d>ELW\x10\xe6#\xcc\xb5\x8cuiV\x96\xb0\xcd\xff.\x16\x14i\xc8\x16e\xf8\x19I\x1f\xe2\xd8\xa4\x8eE\x00\xd1E\x17m\x89e\xcd\x8b[\xe0\x19\x1an\xc9S\xb6I\x00:\xa4\xec$\x14(\x9a\x10f\x83\xf0C\x11)\xa6\xc4\xcd\x80Y\xdd\xf0\xe6\x89f\xdc\t4\x1e\x8b\xa3\xa8\x8d\xaa\xd3\xb8\xc2Ku\x9a\xd0\x01\x88\\\x9b\xcb\xb9\xc2\xd04\xaa<\xf4SA[\x91\xcf(\xc3\xfc\xd3k\x89a\x06\xe3H\x10\x16\x9eXf\xb2g\xa2(\xefN\x14R\xd3z\xa8\xe8\xfc}\x81\xc6\xf3\xc6\xad\x8d(\x9bW\x80U\x96P/\xe7f\x8f\xf1\xbdSJz\x1c\xf1\xbe_\xec\xf2Y\xbd\xda\xa5w\x0e\xd1\xca \xe1\xb0ku_X\xf3C*\xc3\x94\xed\xd9\x86\xf3I\xe6\xda\x87kLL\x9b=\x1e\xcf\x1f'}
>2023-05-19 04:41:51,668 [salt.minion      :1717][INFO    ][3004] User root Executing command state.highstate with jid 20230519024151658886
>2023-05-19 04:41:51,668 [salt.minion      :1721][DEBUG   ][3004] Command details {'fun': 'state.highstate', 'arg': [], 'tgt': '*', 'jid': '20230519024151658886', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}
>2023-05-19 04:41:51,674 [salt.utils.process:1145][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024151658886) added
>2023-05-19 04:41:51,783 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,784 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded jinja.render
>2023-05-19 04:41:51,785 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,785 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded yaml.render
>2023-05-19 04:41:51,789 [salt.minion      :1917][INFO    ][3124] Starting a new job 20230519024151658886 with PID 3124
>2023-05-19 04:41:51,790 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,791 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'splay' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,794 [salt.utils.lazy  :103 ][DEBUG   ][3124] Could not LazyLoad direct_call.allow_missing_func: 'direct_call.allow_missing_func' is not available.
>2023-05-19 04:41:51,803 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,803 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded state.highstate
>2023-05-19 04:41:51,805 [salt.loader.lazy :863 ][DEBUG   ][3124] Override  __grains__: <module 'salt.loaded.int.module.grains' from '/usr/lib/python3.6/site-packages/salt/modules/grains.py'>
>2023-05-19 04:41:51,805 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'grains' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,805 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded grains.get
>2023-05-19 04:41:51,807 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,807 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded config.option
>2023-05-19 04:41:51,820 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:51,820 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded saltutil.is_running
>2023-05-19 04:41:51,832 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:51,839 [salt.state       :769 ][DEBUG   ][3124] Gathering pillar data for state run
>2023-05-19 04:41:51,840 [salt.pillar      :71  ][DEBUG   ][3124] Determining pillar cache
>2023-05-19 04:41:51,840 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,210 [salt.crypt       :208 ][DEBUG   ][3124] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:52,211 [salt.crypt       :900 ][DEBUG   ][3124] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:52,218 [salt.crypt       :255 ][DEBUG   ][3124] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:41:52,219 [salt.crypt       :216 ][DEBUG   ][3124] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:41:52,219 [salt.crypt       :257 ][DEBUG   ][3124] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:41:52,220 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,220 [salt.state       :784 ][DEBUG   ][3124] Finished gathering pillar data for state run
>2023-05-19 04:41:52,220 [salt.state       :1274][INFO    ][3124] Loading fresh modules for state activity
>2023-05-19 04:41:52,304 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,304 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded jinja.render
>2023-05-19 04:41:52,305 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,305 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded yaml.render
>2023-05-19 04:41:52,322 [salt.fileclient  :1196][DEBUG   ][3124] In saltenv 'base', looking at rel_path 'top.sls' to resolve 'salt://top.sls'
>2023-05-19 04:41:52,323 [salt.fileclient  :1205][DEBUG   ][3124] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/top.sls' to resolve 'salt://top.sls'
>2023-05-19 04:41:52,323 [salt.fileclient  :1218][DEBUG   ][3124] Fetching file from saltenv 'base', ** attempting ** 'salt://top.sls'
>2023-05-19 04:41:52,323 [salt.fileclient  :1246][DEBUG   ][3124] No dest file found
>2023-05-19 04:41:52,335 [salt.fileclient  :1333][INFO    ][3124] Fetching file from saltenv 'base', ** done ** 'top.sls'
>2023-05-19 04:41:52,335 [salt.template    :53  ][DEBUG   ][3124] compile template: /var/cache/salt/minion/files/base/top.sls
>2023-05-19 04:41:52,336 [salt.utils.jinja :80  ][DEBUG   ][3124] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:41:52,342 [salt.template    :23  ][PROFILE ][3124] Time (in seconds) to render '/var/cache/salt/minion/files/base/top.sls' using 'jinja' renderer: 0.0057010650634765625
>2023-05-19 04:41:52,342 [salt.template    :120 ][DEBUG   ][3124] Rendered data from file: /var/cache/salt/minion/files/base/top.sls:
>---
>base:
>  '*':
>    - pkg
>
>2023-05-19 04:41:52,343 [salt.template    :23  ][PROFILE ][3124] Time (in seconds) to render '/var/cache/salt/minion/files/base/top.sls' using 'yaml' renderer: 0.0009448528289794922
>2023-05-19 04:41:52,344 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'confirm_top' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,344 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded confirm_top.confirm_top
>2023-05-19 04:41:52,350 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'compound_match' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,350 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded compound_match.match
>2023-05-19 04:41:52,352 [salt.loaded.int.matchers.compound_match:44  ][DEBUG   ][3124] compound_match: minion ? *
>2023-05-19 04:41:52,353 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'glob_match' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,353 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded glob_match.match
>2023-05-19 04:41:52,353 [salt.loaded.int.matchers.compound_match:128 ][DEBUG   ][3124] compound_match minion ? "*" => "True"
>2023-05-19 04:41:52,353 [salt.crypt       :208 ][DEBUG   ][3124] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:41:52,354 [salt.crypt       :900 ][DEBUG   ][3124] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:41:52,399 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,400 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded saltutil.sync_all
>2023-05-19 04:41:52,400 [salt.loaded.int.module.saltutil:1048][DEBUG   ][3124] Syncing all
>2023-05-19 04:41:52,400 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/clouds'
>2023-05-19 04:41:52,410 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,410 [salt.utils.extmods:80  ][INFO    ][3124] Syncing clouds for environment 'base'
>2023-05-19 04:41:52,410 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_clouds, for base
>2023-05-19 04:41:52,411 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_clouds/' for environment 'base'
>2023-05-19 04:41:52,417 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_clouds'
>2023-05-19 04:41:52,417 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,419 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/beacons'
>2023-05-19 04:41:52,428 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,429 [salt.utils.extmods:80  ][INFO    ][3124] Syncing beacons for environment 'base'
>2023-05-19 04:41:52,429 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_beacons, for base
>2023-05-19 04:41:52,429 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_beacons/' for environment 'base'
>2023-05-19 04:41:52,439 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_beacons'
>2023-05-19 04:41:52,439 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,441 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/modules'
>2023-05-19 04:41:52,450 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,451 [salt.utils.extmods:80  ][INFO    ][3124] Syncing modules for environment 'base'
>2023-05-19 04:41:52,451 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_modules, for base
>2023-05-19 04:41:52,451 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_modules/' for environment 'base'
>2023-05-19 04:41:52,456 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_modules'
>2023-05-19 04:41:52,456 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,457 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/states'
>2023-05-19 04:41:52,468 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,468 [salt.utils.extmods:80  ][INFO    ][3124] Syncing states for environment 'base'
>2023-05-19 04:41:52,468 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_states, for base
>2023-05-19 04:41:52,468 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_states/' for environment 'base'
>2023-05-19 04:41:52,474 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_states'
>2023-05-19 04:41:52,474 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,476 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/sdb'
>2023-05-19 04:41:52,485 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,485 [salt.utils.extmods:80  ][INFO    ][3124] Syncing sdb for environment 'base'
>2023-05-19 04:41:52,485 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_sdb, for base
>2023-05-19 04:41:52,485 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_sdb/' for environment 'base'
>2023-05-19 04:41:52,491 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_sdb'
>2023-05-19 04:41:52,491 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,492 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/grains'
>2023-05-19 04:41:52,502 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,502 [salt.utils.extmods:80  ][INFO    ][3124] Syncing grains for environment 'base'
>2023-05-19 04:41:52,502 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_grains, for base
>2023-05-19 04:41:52,503 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_grains/' for environment 'base'
>2023-05-19 04:41:52,510 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_grains'
>2023-05-19 04:41:52,510 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,511 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/renderers'
>2023-05-19 04:41:52,521 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,521 [salt.utils.extmods:80  ][INFO    ][3124] Syncing renderers for environment 'base'
>2023-05-19 04:41:52,521 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_renderers, for base
>2023-05-19 04:41:52,521 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_renderers/' for environment 'base'
>2023-05-19 04:41:52,529 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_renderers'
>2023-05-19 04:41:52,529 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,530 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/returners'
>2023-05-19 04:41:52,539 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,540 [salt.utils.extmods:80  ][INFO    ][3124] Syncing returners for environment 'base'
>2023-05-19 04:41:52,540 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_returners, for base
>2023-05-19 04:41:52,540 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_returners/' for environment 'base'
>2023-05-19 04:41:52,548 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_returners'
>2023-05-19 04:41:52,548 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,549 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/output'
>2023-05-19 04:41:52,558 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,559 [salt.utils.extmods:80  ][INFO    ][3124] Syncing output for environment 'base'
>2023-05-19 04:41:52,559 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_output, for base
>2023-05-19 04:41:52,559 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_output/' for environment 'base'
>2023-05-19 04:41:52,566 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_output'
>2023-05-19 04:41:52,566 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,567 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/utils'
>2023-05-19 04:41:52,577 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,577 [salt.utils.extmods:80  ][INFO    ][3124] Syncing utils for environment 'base'
>2023-05-19 04:41:52,577 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_utils, for base
>2023-05-19 04:41:52,577 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_utils/' for environment 'base'
>2023-05-19 04:41:52,586 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_utils'
>2023-05-19 04:41:52,587 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,588 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
>2023-05-19 04:41:52,600 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,601 [salt.utils.extmods:80  ][INFO    ][3124] Syncing log_handlers for environment 'base'
>2023-05-19 04:41:52,601 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_log_handlers, for base
>2023-05-19 04:41:52,601 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_log_handlers/' for environment 'base'
>2023-05-19 04:41:52,606 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_log_handlers'
>2023-05-19 04:41:52,606 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,607 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/executors'
>2023-05-19 04:41:52,617 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,617 [salt.utils.extmods:80  ][INFO    ][3124] Syncing executors for environment 'base'
>2023-05-19 04:41:52,617 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_executors, for base
>2023-05-19 04:41:52,617 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_executors/' for environment 'base'
>2023-05-19 04:41:52,623 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_executors'
>2023-05-19 04:41:52,623 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,624 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/proxy'
>2023-05-19 04:41:52,633 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,634 [salt.utils.extmods:80  ][INFO    ][3124] Syncing proxy for environment 'base'
>2023-05-19 04:41:52,634 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_proxy, for base
>2023-05-19 04:41:52,634 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_proxy/' for environment 'base'
>2023-05-19 04:41:52,643 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_proxy'
>2023-05-19 04:41:52,643 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,645 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/engines'
>2023-05-19 04:41:52,654 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,654 [salt.utils.extmods:80  ][INFO    ][3124] Syncing engines for environment 'base'
>2023-05-19 04:41:52,654 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_engines, for base
>2023-05-19 04:41:52,655 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_engines/' for environment 'base'
>2023-05-19 04:41:52,660 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_engines'
>2023-05-19 04:41:52,660 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,661 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/thorium'
>2023-05-19 04:41:52,671 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,672 [salt.utils.extmods:80  ][INFO    ][3124] Syncing thorium for environment 'base'
>2023-05-19 04:41:52,672 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_thorium, for base
>2023-05-19 04:41:52,672 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_thorium/' for environment 'base'
>2023-05-19 04:41:52,679 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_thorium'
>2023-05-19 04:41:52,679 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,680 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/serializers'
>2023-05-19 04:41:52,689 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,690 [salt.utils.extmods:80  ][INFO    ][3124] Syncing serializers for environment 'base'
>2023-05-19 04:41:52,690 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_serializers, for base
>2023-05-19 04:41:52,690 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_serializers/' for environment 'base'
>2023-05-19 04:41:52,695 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_serializers'
>2023-05-19 04:41:52,696 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,697 [salt.utils.extmods:70  ][INFO    ][3124] Creating module dir '/var/cache/salt/minion/extmods/matchers'
>2023-05-19 04:41:52,706 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:41:52,706 [salt.utils.extmods:80  ][INFO    ][3124] Syncing matchers for environment 'base'
>2023-05-19 04:41:52,707 [salt.utils.extmods:82  ][INFO    ][3124] Loading cache from salt://_matchers, for base
>2023-05-19 04:41:52,707 [salt.fileclient  :241 ][INFO    ][3124] Caching directory '_matchers/' for environment 'base'
>2023-05-19 04:41:52,715 [salt.utils.extmods:96  ][DEBUG   ][3124] Local cache dir: '/var/cache/salt/minion/files/base/_matchers'
>2023-05-19 04:41:52,716 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:41:52,717 [salt.state       :1310][DEBUG   ][3124] Refreshing modules...
>2023-05-19 04:41:52,735 [salt.state       :1274][INFO    ][3124] Loading fresh modules for state activity
>2023-05-19 04:41:52,821 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,822 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded jinja.render
>2023-05-19 04:41:52,822 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,823 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded yaml.render
>2023-05-19 04:41:52,826 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,826 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded saltutil.refresh_modules
>2023-05-19 04:41:52,827 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'event' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,827 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded event.fire
>2023-05-19 04:41:52,828 [salt.utils.event :315 ][DEBUG   ][3124] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:41:52,828 [salt.utils.event :316 ][DEBUG   ][3124] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:41:52,831 [salt.utils.event :823 ][DEBUG   ][3124] Sending event: tag = module_refresh; data = {'_stamp': '2023-05-19T02:41:52.831575'}
>2023-05-19 04:41:52,833 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag 'module_refresh'
>2023-05-19 04:41:52,833 [salt.minion      :2432][DEBUG   ][3004] Refreshing modules. Notify=False
>2023-05-19 04:41:52,835 [salt.loader      :1101][DEBUG   ][3004] Grains refresh requested. Refreshing grains.
>2023-05-19 04:41:52,835 [salt.transport.ipc:373 ][DEBUG   ][3124] Closing IPCMessageClient instance
>2023-05-19 04:41:52,836 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion
>2023-05-19 04:41:52,862 [salt.fileclient  :1196][DEBUG   ][3124] In saltenv 'base', looking at rel_path 'pkg.sls' to resolve 'salt://pkg.sls'
>2023-05-19 04:41:52,863 [salt.fileclient  :1205][DEBUG   ][3124] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/pkg.sls' to resolve 'salt://pkg.sls'
>2023-05-19 04:41:52,863 [salt.fileclient  :1218][DEBUG   ][3124] Fetching file from saltenv 'base', ** attempting ** 'salt://pkg.sls'
>2023-05-19 04:41:52,863 [salt.fileclient  :1246][DEBUG   ][3124] No dest file found
>2023-05-19 04:41:52,879 [salt.fileclient  :1333][INFO    ][3124] Fetching file from saltenv 'base', ** done ** 'pkg.sls'
>2023-05-19 04:41:52,879 [salt.template    :53  ][DEBUG   ][3124] compile template: /var/cache/salt/minion/files/base/pkg.sls
>2023-05-19 04:41:52,879 [salt.utils.jinja :80  ][DEBUG   ][3124] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:41:52,881 [salt.template    :23  ][PROFILE ][3124] Time (in seconds) to render '/var/cache/salt/minion/files/base/pkg.sls' using 'jinja' renderer: 0.0013051033020019531
>2023-05-19 04:41:52,881 [salt.template    :120 ][DEBUG   ][3124] Rendered data from file: /var/cache/salt/minion/files/base/pkg.sls:
>---
>sysstat:
>  pkg.installed:
>    - sources:
>      - sysstat: salt://sysstat.rpm
>
>2023-05-19 04:41:52,882 [salt.template    :23  ][PROFILE ][3124] Time (in seconds) to render '/var/cache/salt/minion/files/base/pkg.sls' using 'yaml' renderer: 0.0012331008911132812
>2023-05-19 04:41:52,888 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,888 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded config.option
>2023-05-19 04:41:52,961 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pkg_resource' are being loaded by dir() on the loaded module
>2023-05-19 04:41:52,964 [salt.config      :2180][DEBUG   ][3004] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:41:52,964 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:41:52,994 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'lowpkg' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,024 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pkg' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,025 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded pkg.install
>2023-05-19 04:41:53,025 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pkg' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,025 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded pkg.installed
>2023-05-19 04:41:53,039 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'aliases' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,040 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'alternatives' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,064 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'archive' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,073 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'artifactory' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,092 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,093 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,094 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,095 [salt.config      :2017][DEBUG   ][3124] Reading configuration from /etc/salt/minion
>2023-05-19 04:41:53,100 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,101 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,107 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,109 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,116 [salt.loader.lazy :863 ][DEBUG   ][3004] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:41:53,116 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,236 [salt.config      :2180][DEBUG   ][3124] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:41:53,237 [salt.config      :2017][DEBUG   ][3124] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:41:53,242 [salt.config      :3620][DEBUG   ][3124] Using cached minion ID from /etc/salt/minion_id: minion
>2023-05-19 04:41:53,327 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'baredoc' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,333 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'beacons' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,335 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'bigip' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,456 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,456 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded zfs.is_supported
>2023-05-19 04:41:53,596 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'btrfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,638 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'path' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,643 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded path.which
>2023-05-19 04:41:53,644 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'chroot' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,653 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cloud' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,654 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cmd' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,659 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'composer' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,660 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'consul' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,662 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'container_resource' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,663 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cp' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,664 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cpan' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,665 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cron' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,666 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cryptdev' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,678 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'data' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,689 [salt.minion      :2448][DEBUG   ][3004] Refreshing beacons.
>2023-05-19 04:41:53,695 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'defaults' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,696 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'devinfo' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,696 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'devmap' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,697 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dig' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,698 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'disk' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,703 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,703 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded config.merge
>2023-05-19 04:41:53,704 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,704 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded mine.update
>2023-05-19 04:41:53,705 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'django' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,706 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dnsmasq' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,706 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dnsutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,719 [salt.loader.lazy :878 ][DEBUG   ][3124] Override  __salt__: <module 'salt.loaded.int.module.dracr' from '/usr/lib/python3.6/site-packages/salt/modules/dracr.py'>
>2023-05-19 04:41:53,720 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'drbd' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,725 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'environ' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,735 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ethtool' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,736 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'extfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,745 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'file' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,747 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'firewalld' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,751 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'freezer' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,754 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'gem' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,759 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'genesis' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,770 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'glassfish' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,795 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'gnome' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,796 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'google_chat' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,800 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'grafana4' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,801 [salt.loader.lazy :863 ][DEBUG   ][3124] Override  __grains__: <module 'salt.loaded.int.module.grains' from '/usr/lib/python3.6/site-packages/salt/modules/grains.py'>
>2023-05-19 04:41:53,801 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'grains' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,835 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'group' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,841 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'hashutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,845 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'helm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,847 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'highstate_doc' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,848 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'hosts' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,848 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'http' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,853 [salt.loader.lazy :960 ][DEBUG   ][3124] The functions from module 'aggregation' are being loaded from the provided __all__ attribute
>2023-05-19 04:41:53,855 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'args' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,856 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'asynchronous' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,857 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'atomicfile' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,857 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'aws' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,860 [salt.config      :2017][DEBUG   ][3124] Reading configuration from /etc/salt/minion
>2023-05-19 04:41:53,929 [salt.config      :2180][DEBUG   ][3124] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:41:53,929 [salt.config      :2017][DEBUG   ][3124] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:41:53,932 [salt.config      :3620][DEBUG   ][3124] Using cached minion ID from /etc/salt/minion_id: minion
>2023-05-19 04:41:53,987 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'beacons' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,991 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'boto_elb_tag' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,995 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cache' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,996 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'channel' are being loaded by dir() on the loaded module
>2023-05-19 04:41:53,999 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'cloud' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,000 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'color' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,000 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'compat' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,001 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'configcomparer' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,001 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'configparser' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,002 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'context' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,003 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'crypt' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,003 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ctx' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,004 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'data' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,007 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dateutils' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,007 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'debug' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,008 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'decorators' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,009 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dictdiffer' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,010 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dicttrim' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,010 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dictupdate' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,015 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'dns' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,016 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'doc' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,019 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'entrypoints' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,020 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'environment' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,020 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'error' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,023 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'etcd_util' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,024 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'event' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,026 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'extend' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,027 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'extmods' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,028 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'filebuffer' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,028 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'files' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,031 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'find' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,031 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'fsutils' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,032 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'functools' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,036 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'gitfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,037 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'github' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,037 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'gzip_util' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,038 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'hashutils' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,039 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'http' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,039 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'iam' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,040 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'icinga2' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,041 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'immutabletypes' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,041 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'itertools' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,042 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jid' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,043 [salt.loader.lazy :960 ][DEBUG   ][3124] The functions from module 'jinja' are being loaded from the provided __all__ attribute
>2023-05-19 04:41:54,044 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'job' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,044 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'json' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,045 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'kickstart' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,046 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'kinds' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,047 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'lazy' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,048 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'listdiffer' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,048 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'locales' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,052 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mako' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,052 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'master' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,053 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mattermost' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,055 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'migrations' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,056 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,057 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'minion' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,059 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'minions' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,060 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mount' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,062 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'msazure' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,063 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'msgpack' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,091 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'namecheap' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,092 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'napalm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,094 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nb_popen' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,101 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'network' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,104 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nxos' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,104 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nxos_api' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,105 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'odict' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,106 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'openstack' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,106 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'oset' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,107 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pagerduty' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,115 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'parsers' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,120 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pkg' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,121 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'platform' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,122 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'powershell' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,122 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'preseed' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,125 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'process' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,130 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'profile' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,130 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'proxy' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,131 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'psutil_compat' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,132 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pushover' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,134 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pycrypto' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,135 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pydsl' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,136 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pyinstaller' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,137 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pyobjects' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,138 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'reactor' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,138 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'reclass' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,140 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'roster_matcher' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,146 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'rsax931' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,148 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 's3' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,149 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'saltclass' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,150 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sanitizers' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,153 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'schedule' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,168 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'schema' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,169 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sdb' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,170 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'slack' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,173 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'smb' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,180 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'smtp' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,183 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ssdp' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,184 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ssh' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,185 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,185 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'stringio' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,186 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'stringutils' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,187 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'systemd' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,188 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'templates' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,188 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'textformat' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,192 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'thin' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,193 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'timed_subprocess' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,194 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'timeout' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,194 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'timeutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,195 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'url' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,197 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'user' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,198 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'validate' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,199 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'value' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,225 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'vault' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,228 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'verify' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,229 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'versions' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,229 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'virt' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,232 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'virtualbox' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,241 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'vt' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,242 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'vt_helper' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,327 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'x509' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,329 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'xdg' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,330 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'xmlutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,331 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,332 [salt.loader.lazy :960 ][DEBUG   ][3124] The functions from module 'yamldumper' are being loaded from the provided __all__ attribute
>2023-05-19 04:41:54,332 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yamlencoding' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,335 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yamllint' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,336 [salt.loader.lazy :960 ][DEBUG   ][3124] The functions from module 'yamlloader' are being loaded from the provided __all__ attribute
>2023-05-19 04:41:54,336 [salt.loader.lazy :960 ][DEBUG   ][3124] The functions from module 'yamlloader_old' are being loaded from the provided __all__ attribute
>2023-05-19 04:41:54,337 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yast' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,338 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'zeromq' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,339 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,343 [salt.utils.lazy  :103 ][DEBUG   ][3124] Could not LazyLoad idem.hub: 'idem' __virtual__ returned False: No module named 'pop'
>2023-05-19 04:41:54,349 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'incron' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,358 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ini' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,362 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'inspectlib' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,362 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'inspector' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,363 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'introspect' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,364 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'iosconfig' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,367 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ipset' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,369 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'iptables' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,371 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jboss7' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,372 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jboss7_cli' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,375 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,381 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'k8s' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,384 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'key' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,385 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'keyboard' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,395 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'kmod' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,395 [salt.loader.lazy :878 ][DEBUG   ][3124] Override  __salt__: <module 'salt.loaded.int.module.kubeadm' from '/usr/lib/python3.6/site-packages/salt/modules/kubeadm.py'>
>2023-05-19 04:41:54,396 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'kubeadm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,417 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'acl' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,419 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,422 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'shadow' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,424 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sysctl' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,425 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'locale' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,426 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'locate' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,427 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'log' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,428 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'logrotate' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,455 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mandrill' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,457 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'match' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,459 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mattermost' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,461 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'raid' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,467 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,476 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'minion' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,477 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'random' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,478 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'modjk' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,484 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'mount' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,488 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'msteams' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,496 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nagios_rpc' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,498 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'namecheap_domains' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,498 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'namecheap_domains_dns' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,499 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'namecheap_domains_ns' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,500 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'namecheap_ssl' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,500 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'namecheap_users' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,539 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'netaddress' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,550 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'network' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,557 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nexus' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,558 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nfs3' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,559 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nftables' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,569 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nova' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,583 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nspawn' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,586 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nxos' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,587 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nxos_api' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,588 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'nxos' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,595 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'openscap' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,596 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'openstack_config' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,599 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'opsgenie' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,604 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'out' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,605 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pagerduty' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,606 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pagerduty_util' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,607 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pam' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,611 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'parallels' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,613 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'partition' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,617 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'peeringdb' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,619 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'hue' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,620 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pillar' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,622 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pip' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,626 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'postfix' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,634 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ps' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,635 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'publish' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,648 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pushover' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,650 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'pyenv' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,654 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'quota' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,658 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'random_org' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,660 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'rbenv' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,668 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'rest_sample_utils' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,670 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'restartcheck' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,671 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ret' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,676 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'rsync' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,678 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'rvm' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,679 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 's3' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,679 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 's6' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,680 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'salt_proxy' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,681 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'salt_version' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,682 [salt.loader.lazy :863 ][DEBUG   ][3124] Override  __context__: <module 'salt.loaded.int.module.saltcheck' from '/usr/lib/python3.6/site-packages/salt/modules/saltcheck.py'>
>2023-05-19 04:41:54,682 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'saltcheck' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,689 [salt.loader.lazy :789 ][DEBUG   ][3124] Failed to import module saltsupport:
>Traceback (most recent call last):
>  File "/usr/lib/python3.6/site-packages/salt/loader/lazy.py", line 772, in _load_module
>    mod = self.run(spec.loader.load_module)
>  File "/usr/lib/python3.6/site-packages/salt/loader/lazy.py", line 1234, in run
>    return self._last_context.run(self._run_as, _func_or_method, *args, **kwargs)
>  File "/usr/lib/python3.6/site-packages/contextvars/__init__.py", line 38, in run
>    return callable(*args, **kwargs)
>  File "/usr/lib/python3.6/site-packages/salt/loader/lazy.py", line 1249, in _run_as
>    return _func_or_method(*args, **kwargs)
>  File "<frozen importlib._bootstrap_external>", line 399, in _check_name_wrapper
>  File "<frozen importlib._bootstrap_external>", line 823, in load_module
>  File "<frozen importlib._bootstrap_external>", line 682, in load_module
>  File "<frozen importlib._bootstrap>", line 265, in _load_module_shim
>  File "<frozen importlib._bootstrap>", line 684, in _load
>  File "<frozen importlib._bootstrap>", line 665, in _load_unlocked
>  File "<frozen importlib._bootstrap_external>", line 678, in exec_module
>  File "<frozen importlib._bootstrap>", line 219, in _call_with_frames_removed
>  File "/usr/lib/python3.6/site-packages/salt/modules/saltsupport.py", line 41, in <module>
>    from salt.cli.support.collector import SaltSupport, SupportDataCollector
>  File "/usr/lib/python3.6/site-packages/salt/cli/support/collector.py", line 19, in <module>
>    import salt.ext.six as six
>ModuleNotFoundError: No module named 'salt.ext.six'
>2023-05-19 04:41:54,694 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'schedule' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,756 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'scsi' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,756 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sdb' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,757 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'seed' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,763 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'serverdensity_device' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,767 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'slack' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,770 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'slsutil' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,773 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'smbios' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,776 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'smtp' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,795 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'snapper' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,804 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'solrcloud' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,810 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sqlite3' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,812 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ssh' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,813 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,815 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'status' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,816 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'statuspage' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,816 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'supervisord' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,819 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'ip' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,825 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sysfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,826 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'syslog_ng' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,842 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'sys' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,844 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'system' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,847 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'service' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,849 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'telegram' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,850 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'telemetry' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,851 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'temp' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,852 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'test' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,858 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'timezone' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,878 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'tls' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,888 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'travisci' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,893 [salt.loader.lazy :878 ][DEBUG   ][3124] Override  __salt__: <module 'salt.loaded.int.module.udev' from '/usr/lib/python3.6/site-packages/salt/modules/udev.py'>
>2023-05-19 04:41:54,894 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'udev' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,896 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'uptime' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,897 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'user' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,901 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'vault' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,902 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'vbox_guest' are being loaded by dir() on the loaded module
>2023-05-19 04:41:54,916 [salt.loader.lazy :863 ][DEBUG   ][3124] Override  __pillar__: <module 'salt.loaded.int.module.virtualenv_mod' from '/usr/lib/python3.6/site-packages/salt/modules/virtualenv_mod.py'>
>2023-05-19 04:41:54,916 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'virtualenv' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,055 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'vsphere' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,075 [salt.loaded.int.module.win_dsc:39  ][DEBUG   ][3124] DSC: Only available on Windows systems
>2023-05-19 04:41:55,096 [salt.loaded.int.module.win_psget:31  ][DEBUG   ][3124] Module PSGet: Only available on Windows systems
>2023-05-19 04:41:55,101 [salt.loaded.int.module.win_shortcut:49  ][DEBUG   ][3124] Shortcut module only available on Windows systems
>2023-05-19 04:41:55,128 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'x509' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,134 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'xfs' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,134 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'xml' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,139 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'zabbix' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,141 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'buildout' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,141 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'zenoss' are being loaded by dir() on the loaded module
>2023-05-19 04:41:55,164 [salt.utils.lazy  :103 ][DEBUG   ][3124] Could not LazyLoad pkg.ex_mod_init: 'pkg.ex_mod_init' is not available.
>2023-05-19 04:41:55,165 [salt.state       :2269][INFO    ][3124] Running state [sysstat] at time 04:41:55.165096
>2023-05-19 04:41:55,166 [salt.state       :2301][INFO    ][3124] Executing state pkg.installed for [sysstat]
>2023-05-19 04:41:55,169 [salt.loaded.int.module.cmdmod:438 ][INFO    ][3124] Executing command rpm in directory '/root'
>2023-05-19 04:41:56,410 [salt.loaded.int.module.zypperpkg:412 ][DEBUG   ][3124] Calling Zypper: zypper --non-interactive refresh --force
>2023-05-19 04:41:56,411 [salt.loaded.int.module.cmdmod:438 ][INFO    ][3124] Executing command zypper in directory '/root'
>2023-05-19 04:42:15,676 [salt.crypt       :506 ][DEBUG   ][3124] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:15,689 [salt.fileclient  :1196][DEBUG   ][3124] In saltenv 'base', looking at rel_path 'sysstat.rpm' to resolve 'salt://sysstat.rpm'
>2023-05-19 04:42:15,690 [salt.fileclient  :1205][DEBUG   ][3124] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/sysstat.rpm' to resolve 'salt://sysstat.rpm'
>2023-05-19 04:42:15,690 [salt.fileclient  :1218][DEBUG   ][3124] Fetching file from saltenv 'base', ** attempting ** 'salt://sysstat.rpm'
>2023-05-19 04:42:15,690 [salt.fileclient  :1246][DEBUG   ][3124] No dest file found
>2023-05-19 04:42:15,716 [salt.fileclient  :1333][INFO    ][3124] Fetching file from saltenv 'base', ** done ** 'sysstat.rpm'
>2023-05-19 04:42:15,716 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:42:15,743 [salt.loaded.int.module.zypperpkg:412 ][DEBUG   ][3124] Calling Zypper: systemd-run --scope zypper --non-interactive --no-refresh install --auto-agree-with-licenses --name /var/cache/salt/minion/files/base/sysstat.rpm
>2023-05-19 04:42:15,744 [salt.loaded.int.module.cmdmod:438 ][INFO    ][3124] Executing command systemd-run in directory '/root'
>2023-05-19 04:42:20,483 [salt.loaded.int.module.cmdmod:438 ][INFO    ][3124] Executing command rpm in directory '/root'
>2023-05-19 04:42:21,835 [salt.state       :325 ][INFO    ][3124] Made the following changes:
>'sysstat' changed from 'absent' to '12.0.2-3.33.1'
>'procmail' changed from 'absent' to '3.22-2.34'
>
>2023-05-19 04:42:21,836 [salt.state       :1310][DEBUG   ][3124] Refreshing modules...
>2023-05-19 04:42:21,880 [salt.state       :1274][INFO    ][3124] Loading fresh modules for state activity
>2023-05-19 04:42:21,961 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:21,962 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded jinja.render
>2023-05-19 04:42:21,962 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:21,962 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded yaml.render
>2023-05-19 04:42:21,966 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:21,966 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded saltutil.refresh_modules
>2023-05-19 04:42:21,967 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'event' are being loaded by dir() on the loaded module
>2023-05-19 04:42:21,967 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded event.fire
>2023-05-19 04:42:21,968 [salt.utils.event :315 ][DEBUG   ][3124] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:42:21,968 [salt.utils.event :316 ][DEBUG   ][3124] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:42:21,971 [salt.utils.event :823 ][DEBUG   ][3124] Sending event: tag = module_refresh; data = {'_stamp': '2023-05-19T02:42:21.971475'}
>2023-05-19 04:42:21,972 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag 'module_refresh'
>2023-05-19 04:42:21,972 [salt.minion      :2432][DEBUG   ][3004] Refreshing modules. Notify=False
>2023-05-19 04:42:21,973 [salt.loader      :1101][DEBUG   ][3004] Grains refresh requested. Refreshing grains.
>2023-05-19 04:42:21,973 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion
>2023-05-19 04:42:21,975 [salt.transport.ipc:373 ][DEBUG   ][3124] Closing IPCMessageClient instance
>2023-05-19 04:42:21,975 [salt.state       :2478][INFO    ][3124] Completed state [sysstat] at time 04:42:21.975761 (duration_in_ms=26810.666)
>2023-05-19 04:42:21,976 [salt.state       :3567][DEBUG   ][3124] File /var/cache/salt/minion/accumulator/139959038091392 does not exist, no need to cleanup
>2023-05-19 04:42:21,976 [salt.loader.lazy :967 ][DEBUG   ][3124] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:42:21,977 [salt.utils.lazy  :99  ][DEBUG   ][3124] LazyLoaded state.check_result
>2023-05-19 04:42:21,977 [salt.channel.client:313 ][DEBUG   ][3124] Closing AsyncReqChannel instance
>2023-05-19 04:42:21,978 [salt.minion      :2196][INFO    ][3124] Returning information for job: 20230519024151658886
>2023-05-19 04:42:21,979 [salt.utils.event :315 ][DEBUG   ][3124] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:42:21,979 [salt.utils.event :316 ][DEBUG   ][3124] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:42:21,988 [salt.utils.event :823 ][DEBUG   ][3124] Sending event: tag = __master_req_channel_payload; data = {'cmd': '_return', 'id': 'minion', 'success': True, 'return': {'pkg_|-sysstat_|-sysstat_|-installed': {'name': 'sysstat', 'changes': {'sysstat': {'old': '', 'new': '12.0.2-3.33.1'}, 'procmail': {'old': '', 'new': '3.22-2.34'}}, 'result': True, 'comment': 'The following packages were installed/updated: sysstat', '__sls__': 'pkg', '__run_num__': 0, 'start_time': '04:41:55.165095', 'duration': 26810.666, '__id__': 'sysstat'}}, 'retcode': 0, 'jid': '20230519024151658886', 'fun': 'state.highstate', 'fun_args': [], 'out': 'highstate', '_stamp': '2023-05-19T02:42:21.988055'}
>2023-05-19 04:42:21,988 [salt.transport.ipc:373 ][DEBUG   ][3124] Closing IPCMessageClient instance
>2023-05-19 04:42:21,989 [salt.minion      :2065][DEBUG   ][3124] minion return: {'success': True, 'return': {'pkg_|-sysstat_|-sysstat_|-installed': {'name': 'sysstat', 'changes': {'sysstat': {'old': '', 'new': '12.0.2-3.33.1'}, 'procmail': {'old': '', 'new': '3.22-2.34'}}, 'result': True, 'comment': 'The following packages were installed/updated: sysstat', '__sls__': 'pkg', '__run_num__': 0, 'start_time': '04:41:55.165095', 'duration': 26810.666, '__id__': 'sysstat'}}, 'retcode': 0, 'jid': '20230519024151658886', 'fun': 'state.highstate', 'fun_args': []}
>2023-05-19 04:42:22,051 [salt.config      :2180][DEBUG   ][3004] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:42:22,052 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:42:22,104 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,104 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,105 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,107 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,108 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,110 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,111 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,114 [salt.loader.lazy :863 ][DEBUG   ][3004] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:42:22,114 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,291 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,292 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded zfs.is_supported
>2023-05-19 04:42:22,352 [salt.minion      :2448][DEBUG   ][3004] Refreshing beacons.
>2023-05-19 04:42:22,358 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,358 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded config.merge
>2023-05-19 04:42:22,359 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:42:22,360 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded mine.update
>2023-05-19 04:42:22,360 [salt.utils.process:1156][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024151658886) cleaned up
>2023-05-19 04:42:22,361 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:42:22,361 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 8 seconds (randomized)
>2023-05-19 04:42:24,322 [salt.channel.client:427 ][DEBUG   ][3004] PubChannel received: {'enc': 'aes', 'load': {'fun': 'state.highstate', 'arg': [], 'tgt': '*', 'jid': '20230519024224301236', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}, 'sig': b'`9\xd9\xe5\xf0\xb7\xcbr\x95$\x14\x00g\xd9\x0b-\xdd\x9b\xe53\xa3\xf0\xcd"\xadl-o\xfdy\x8d\x7f\xe0\x99g\xbb\xbcD\xc5o=m\x9a(+\x8f"\xb7\x0b\xc6\x1dY\xb5\xe6~\xe5\xdc\x847:\x85v\xc5j\x91\x8c\xae\xfd\xb6M2\xf2\x99\xb4\xc8\x8b,\x13\x88\x00\x1dO\n\x8c\x96\xe2v\x12\xd8\xe7=\x83\x91\xd2"\xff#\x9b\xd2\x89\xcbkb\xd7\x89-|O\x05L_o\xbbR\x81\x14o\xe2\xa99\xf1i5\xac\xec\xe5\x82 \xf6Wp\xf2\x98\x9a\x10|\xa3\xf2\xc1;M\x1d&\xdb\x85j\xf8\x07x\x8f\xc9\xfe\x02\xdf\x9bb\xebPX\xe6\xde\xd7\x88*\xae\xcb\xc8\xcb\x91\x13\xb1\x01\xfd\xc5\x11\xf0b\xb5\xeb\x99\x04\ty\xde\xa2\xed\x0c\xea\x08\xa2\x04\x86\x05w&[\xae\xfa\x12\xadO\xc7t\x04\xdd\x9e\x0e\xcf\x1d/\xc5\xc9\xe701Lu\x12a\xca\xf2\x9eh~\xce9A\x13Ss\x06\x856\x1fzE\xb2\xdf\xb0\xeb\xd1@&\xe2)\xc9\x19?\x01\x93\xd1Z\x94]\x06\x14'}
>2023-05-19 04:42:24,322 [salt.minion      :1717][INFO    ][3004] User root Executing command state.highstate with jid 20230519024224301236
>2023-05-19 04:42:24,322 [salt.minion      :1721][DEBUG   ][3004] Command details {'fun': 'state.highstate', 'arg': [], 'tgt': '*', 'jid': '20230519024224301236', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}
>2023-05-19 04:42:24,327 [salt.utils.process:1145][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024224301236) added
>2023-05-19 04:42:24,437 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,437 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded jinja.render
>2023-05-19 04:42:24,438 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,439 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded yaml.render
>2023-05-19 04:42:24,443 [salt.minion      :1917][INFO    ][4917] Starting a new job 20230519024224301236 with PID 4917
>2023-05-19 04:42:24,444 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,445 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'splay' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,447 [salt.utils.lazy  :103 ][DEBUG   ][4917] Could not LazyLoad direct_call.allow_missing_func: 'direct_call.allow_missing_func' is not available.
>2023-05-19 04:42:24,455 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,455 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded state.highstate
>2023-05-19 04:42:24,457 [salt.loader.lazy :863 ][DEBUG   ][4917] Override  __grains__: <module 'salt.loaded.int.module.grains' from '/usr/lib/python3.6/site-packages/salt/modules/grains.py'>
>2023-05-19 04:42:24,457 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'grains' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,458 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded grains.get
>2023-05-19 04:42:24,459 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,459 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded config.option
>2023-05-19 04:42:24,474 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,474 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded saltutil.is_running
>2023-05-19 04:42:24,486 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:24,495 [salt.state       :769 ][DEBUG   ][4917] Gathering pillar data for state run
>2023-05-19 04:42:24,495 [salt.pillar      :71  ][DEBUG   ][4917] Determining pillar cache
>2023-05-19 04:42:24,495 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:24,715 [salt.crypt       :208 ][DEBUG   ][4917] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:42:24,716 [salt.crypt       :900 ][DEBUG   ][4917] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:42:24,729 [salt.crypt       :255 ][DEBUG   ][4917] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:42:24,729 [salt.crypt       :216 ][DEBUG   ][4917] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:42:24,730 [salt.crypt       :257 ][DEBUG   ][4917] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:42:24,730 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:24,731 [salt.state       :784 ][DEBUG   ][4917] Finished gathering pillar data for state run
>2023-05-19 04:42:24,731 [salt.state       :1274][INFO    ][4917] Loading fresh modules for state activity
>2023-05-19 04:42:24,787 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,788 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded jinja.render
>2023-05-19 04:42:24,788 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,788 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded yaml.render
>2023-05-19 04:42:24,805 [salt.fileclient  :1196][DEBUG   ][4917] In saltenv 'base', looking at rel_path 'top.sls' to resolve 'salt://top.sls'
>2023-05-19 04:42:24,806 [salt.fileclient  :1205][DEBUG   ][4917] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/top.sls' to resolve 'salt://top.sls'
>2023-05-19 04:42:24,806 [salt.fileclient  :1218][DEBUG   ][4917] Fetching file from saltenv 'base', ** attempting ** 'salt://top.sls'
>2023-05-19 04:42:24,806 [salt.fileclient  :1246][DEBUG   ][4917] No dest file found
>2023-05-19 04:42:24,849 [salt.fileclient  :1333][INFO    ][4917] Fetching file from saltenv 'base', ** done ** 'top.sls'
>2023-05-19 04:42:24,849 [salt.template    :53  ][DEBUG   ][4917] compile template: /var/cache/salt/minion/files/base/top.sls
>2023-05-19 04:42:24,850 [salt.utils.jinja :80  ][DEBUG   ][4917] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:42:24,855 [salt.template    :23  ][PROFILE ][4917] Time (in seconds) to render '/var/cache/salt/minion/files/base/top.sls' using 'jinja' renderer: 0.005409717559814453
>2023-05-19 04:42:24,856 [salt.template    :120 ][DEBUG   ][4917] Rendered data from file: /var/cache/salt/minion/files/base/top.sls:
>---
>base:
>  '*':
>    - user
>
>2023-05-19 04:42:24,857 [salt.template    :23  ][PROFILE ][4917] Time (in seconds) to render '/var/cache/salt/minion/files/base/top.sls' using 'yaml' renderer: 0.0009634494781494141
>2023-05-19 04:42:24,857 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'confirm_top' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,858 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded confirm_top.confirm_top
>2023-05-19 04:42:24,888 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'compound_match' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,888 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded compound_match.match
>2023-05-19 04:42:24,890 [salt.loaded.int.matchers.compound_match:44  ][DEBUG   ][4917] compound_match: minion ? *
>2023-05-19 04:42:24,891 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'glob_match' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,891 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded glob_match.match
>2023-05-19 04:42:24,891 [salt.loaded.int.matchers.compound_match:128 ][DEBUG   ][4917] compound_match minion ? "*" => "True"
>2023-05-19 04:42:24,892 [salt.crypt       :208 ][DEBUG   ][4917] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:42:24,892 [salt.crypt       :900 ][DEBUG   ][4917] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:42:24,931 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:24,931 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded saltutil.sync_all
>2023-05-19 04:42:24,931 [salt.loaded.int.module.saltutil:1048][DEBUG   ][4917] Syncing all
>2023-05-19 04:42:24,932 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/clouds'
>2023-05-19 04:42:24,941 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:24,941 [salt.utils.extmods:80  ][INFO    ][4917] Syncing clouds for environment 'base'
>2023-05-19 04:42:24,941 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_clouds, for base
>2023-05-19 04:42:24,941 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_clouds/' for environment 'base'
>2023-05-19 04:42:24,947 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_clouds'
>2023-05-19 04:42:24,948 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:24,949 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/beacons'
>2023-05-19 04:42:24,960 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:24,960 [salt.utils.extmods:80  ][INFO    ][4917] Syncing beacons for environment 'base'
>2023-05-19 04:42:24,961 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_beacons, for base
>2023-05-19 04:42:24,961 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_beacons/' for environment 'base'
>2023-05-19 04:42:24,966 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_beacons'
>2023-05-19 04:42:24,966 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:24,968 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/modules'
>2023-05-19 04:42:24,979 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:24,979 [salt.utils.extmods:80  ][INFO    ][4917] Syncing modules for environment 'base'
>2023-05-19 04:42:24,979 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_modules, for base
>2023-05-19 04:42:24,980 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_modules/' for environment 'base'
>2023-05-19 04:42:24,985 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_modules'
>2023-05-19 04:42:24,985 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:24,986 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/states'
>2023-05-19 04:42:24,996 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:24,996 [salt.utils.extmods:80  ][INFO    ][4917] Syncing states for environment 'base'
>2023-05-19 04:42:24,996 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_states, for base
>2023-05-19 04:42:24,996 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_states/' for environment 'base'
>2023-05-19 04:42:25,001 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_states'
>2023-05-19 04:42:25,001 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,002 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/sdb'
>2023-05-19 04:42:25,012 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,012 [salt.utils.extmods:80  ][INFO    ][4917] Syncing sdb for environment 'base'
>2023-05-19 04:42:25,012 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_sdb, for base
>2023-05-19 04:42:25,012 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_sdb/' for environment 'base'
>2023-05-19 04:42:25,017 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_sdb'
>2023-05-19 04:42:25,017 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,018 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/grains'
>2023-05-19 04:42:25,027 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,027 [salt.utils.extmods:80  ][INFO    ][4917] Syncing grains for environment 'base'
>2023-05-19 04:42:25,027 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_grains, for base
>2023-05-19 04:42:25,028 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_grains/' for environment 'base'
>2023-05-19 04:42:25,032 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_grains'
>2023-05-19 04:42:25,032 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,033 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/renderers'
>2023-05-19 04:42:25,042 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,042 [salt.utils.extmods:80  ][INFO    ][4917] Syncing renderers for environment 'base'
>2023-05-19 04:42:25,042 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_renderers, for base
>2023-05-19 04:42:25,043 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_renderers/' for environment 'base'
>2023-05-19 04:42:25,047 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_renderers'
>2023-05-19 04:42:25,047 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,048 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/returners'
>2023-05-19 04:42:25,057 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,057 [salt.utils.extmods:80  ][INFO    ][4917] Syncing returners for environment 'base'
>2023-05-19 04:42:25,057 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_returners, for base
>2023-05-19 04:42:25,058 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_returners/' for environment 'base'
>2023-05-19 04:42:25,064 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_returners'
>2023-05-19 04:42:25,064 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,065 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/output'
>2023-05-19 04:42:25,074 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,074 [salt.utils.extmods:80  ][INFO    ][4917] Syncing output for environment 'base'
>2023-05-19 04:42:25,074 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_output, for base
>2023-05-19 04:42:25,075 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_output/' for environment 'base'
>2023-05-19 04:42:25,084 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_output'
>2023-05-19 04:42:25,084 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,085 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/utils'
>2023-05-19 04:42:25,095 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,095 [salt.utils.extmods:80  ][INFO    ][4917] Syncing utils for environment 'base'
>2023-05-19 04:42:25,095 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_utils, for base
>2023-05-19 04:42:25,095 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_utils/' for environment 'base'
>2023-05-19 04:42:25,100 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_utils'
>2023-05-19 04:42:25,100 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,101 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
>2023-05-19 04:42:25,110 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,110 [salt.utils.extmods:80  ][INFO    ][4917] Syncing log_handlers for environment 'base'
>2023-05-19 04:42:25,110 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_log_handlers, for base
>2023-05-19 04:42:25,110 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_log_handlers/' for environment 'base'
>2023-05-19 04:42:25,115 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_log_handlers'
>2023-05-19 04:42:25,115 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,116 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/executors'
>2023-05-19 04:42:25,125 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,125 [salt.utils.extmods:80  ][INFO    ][4917] Syncing executors for environment 'base'
>2023-05-19 04:42:25,125 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_executors, for base
>2023-05-19 04:42:25,126 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_executors/' for environment 'base'
>2023-05-19 04:42:25,132 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_executors'
>2023-05-19 04:42:25,132 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,133 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/proxy'
>2023-05-19 04:42:25,142 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,142 [salt.utils.extmods:80  ][INFO    ][4917] Syncing proxy for environment 'base'
>2023-05-19 04:42:25,142 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_proxy, for base
>2023-05-19 04:42:25,143 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_proxy/' for environment 'base'
>2023-05-19 04:42:25,152 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_proxy'
>2023-05-19 04:42:25,152 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,153 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/engines'
>2023-05-19 04:42:25,162 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,162 [salt.utils.extmods:80  ][INFO    ][4917] Syncing engines for environment 'base'
>2023-05-19 04:42:25,163 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_engines, for base
>2023-05-19 04:42:25,163 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_engines/' for environment 'base'
>2023-05-19 04:42:25,167 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_engines'
>2023-05-19 04:42:25,167 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,168 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/thorium'
>2023-05-19 04:42:25,177 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,177 [salt.utils.extmods:80  ][INFO    ][4917] Syncing thorium for environment 'base'
>2023-05-19 04:42:25,178 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_thorium, for base
>2023-05-19 04:42:25,178 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_thorium/' for environment 'base'
>2023-05-19 04:42:25,182 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_thorium'
>2023-05-19 04:42:25,182 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,183 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/serializers'
>2023-05-19 04:42:25,192 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,192 [salt.utils.extmods:80  ][INFO    ][4917] Syncing serializers for environment 'base'
>2023-05-19 04:42:25,192 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_serializers, for base
>2023-05-19 04:42:25,192 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_serializers/' for environment 'base'
>2023-05-19 04:42:25,200 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_serializers'
>2023-05-19 04:42:25,200 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,201 [salt.utils.extmods:70  ][INFO    ][4917] Creating module dir '/var/cache/salt/minion/extmods/matchers'
>2023-05-19 04:42:25,210 [salt.crypt       :506 ][DEBUG   ][4917] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:25,210 [salt.utils.extmods:80  ][INFO    ][4917] Syncing matchers for environment 'base'
>2023-05-19 04:42:25,210 [salt.utils.extmods:82  ][INFO    ][4917] Loading cache from salt://_matchers, for base
>2023-05-19 04:42:25,211 [salt.fileclient  :241 ][INFO    ][4917] Caching directory '_matchers/' for environment 'base'
>2023-05-19 04:42:25,216 [salt.utils.extmods:96  ][DEBUG   ][4917] Local cache dir: '/var/cache/salt/minion/files/base/_matchers'
>2023-05-19 04:42:25,216 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,217 [salt.state       :1310][DEBUG   ][4917] Refreshing modules...
>2023-05-19 04:42:25,235 [salt.state       :1274][INFO    ][4917] Loading fresh modules for state activity
>2023-05-19 04:42:25,290 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,290 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded jinja.render
>2023-05-19 04:42:25,291 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,291 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded yaml.render
>2023-05-19 04:42:25,295 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,295 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded saltutil.refresh_modules
>2023-05-19 04:42:25,296 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'event' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,296 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded event.fire
>2023-05-19 04:42:25,297 [salt.utils.event :315 ][DEBUG   ][4917] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:42:25,297 [salt.utils.event :316 ][DEBUG   ][4917] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:42:25,300 [salt.utils.event :823 ][DEBUG   ][4917] Sending event: tag = module_refresh; data = {'_stamp': '2023-05-19T02:42:25.300020'}
>2023-05-19 04:42:25,301 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag 'module_refresh'
>2023-05-19 04:42:25,301 [salt.minion      :2432][DEBUG   ][3004] Refreshing modules. Notify=False
>2023-05-19 04:42:25,301 [salt.loader      :1101][DEBUG   ][3004] Grains refresh requested. Refreshing grains.
>2023-05-19 04:42:25,301 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion
>2023-05-19 04:42:25,307 [salt.transport.ipc:373 ][DEBUG   ][4917] Closing IPCMessageClient instance
>2023-05-19 04:42:25,334 [salt.fileclient  :1196][DEBUG   ][4917] In saltenv 'base', looking at rel_path 'user.sls' to resolve 'salt://user.sls'
>2023-05-19 04:42:25,335 [salt.fileclient  :1205][DEBUG   ][4917] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/user.sls' to resolve 'salt://user.sls'
>2023-05-19 04:42:25,335 [salt.fileclient  :1218][DEBUG   ][4917] Fetching file from saltenv 'base', ** attempting ** 'salt://user.sls'
>2023-05-19 04:42:25,335 [salt.fileclient  :1246][DEBUG   ][4917] No dest file found
>2023-05-19 04:42:25,350 [salt.fileclient  :1333][INFO    ][4917] Fetching file from saltenv 'base', ** done ** 'user.sls'
>2023-05-19 04:42:25,350 [salt.template    :53  ][DEBUG   ][4917] compile template: /var/cache/salt/minion/files/base/user.sls
>2023-05-19 04:42:25,351 [salt.utils.jinja :80  ][DEBUG   ][4917] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:42:25,352 [salt.template    :23  ][PROFILE ][4917] Time (in seconds) to render '/var/cache/salt/minion/files/base/user.sls' using 'jinja' renderer: 0.0012362003326416016
>2023-05-19 04:42:25,352 [salt.template    :120 ][DEBUG   ][4917] Rendered data from file: /var/cache/salt/minion/files/base/user.sls:
>---
>salttestgroup:
>  group.present
>
>salttestuser:
>  user.present:
>    - fullname: Salt Test
>    - shell: /usr/bin/sh
>    - home: /home/salttestuser
>    - groups:
>      - salttestgroup
>
>2023-05-19 04:42:25,354 [salt.template    :23  ][PROFILE ][4917] Time (in seconds) to render '/var/cache/salt/minion/files/base/user.sls' using 'yaml' renderer: 0.0017971992492675781
>2023-05-19 04:42:25,360 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,360 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded config.option
>2023-05-19 04:42:25,360 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'group' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,361 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded group.present
>2023-05-19 04:42:25,361 [salt.state       :2269][INFO    ][4917] Running state [salttestgroup] at time 04:42:25.361292
>2023-05-19 04:42:25,362 [salt.state       :2301][INFO    ][4917] Executing state group.present for [salttestgroup]
>2023-05-19 04:42:25,376 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'group' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,376 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded group.info
>2023-05-19 04:42:25,379 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'cmd' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,380 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded cmd.run_all
>2023-05-19 04:42:25,380 [salt.loaded.int.module.cmdmod:438 ][INFO    ][4917] Executing command /usr/sbin/groupadd in directory '/root'
>2023-05-19 04:42:25,424 [salt.config      :2180][DEBUG   ][3004] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:42:25,424 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:42:25,460 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'test' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,460 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded test.ping
>2023-05-19 04:42:25,461 [salt.state       :325 ][INFO    ][4917] {'name': 'salttestgroup', 'passwd': 'x', 'gid': 1000, 'members': []}
>2023-05-19 04:42:25,461 [salt.state       :2478][INFO    ][4917] Completed state [salttestgroup] at time 04:42:25.461607 (duration_in_ms=100.315)
>2023-05-19 04:42:25,462 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'user' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,462 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded user.present
>2023-05-19 04:42:25,463 [salt.state       :2269][INFO    ][4917] Running state [salttestuser] at time 04:42:25.463106
>2023-05-19 04:42:25,464 [salt.state       :2301][INFO    ][4917] Executing state user.present for [salttestuser]
>2023-05-19 04:42:25,501 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'shadow' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,501 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded shadow.info
>2023-05-19 04:42:25,507 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'namecheap_users' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,510 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'user' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,510 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded user.info
>2023-05-19 04:42:25,511 [salt.loaded.int.module.cmdmod:438 ][INFO    ][4917] Executing command /usr/sbin/useradd in directory '/root'
>2023-05-19 04:42:25,528 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,529 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,530 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,533 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,536 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,540 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,542 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,549 [salt.loader.lazy :863 ][DEBUG   ][3004] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:42:25,549 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,592 [salt.loaded.int.module.cmdmod:438 ][INFO    ][4917] Executing command /usr/sbin/usermod in directory '/root'
>2023-05-19 04:42:25,641 [salt.loaded.int.module.cmdmod:438 ][INFO    ][4917] Executing command /usr/sbin/usermod in directory '/root'
>2023-05-19 04:42:25,688 [salt.loaded.int.module.cmdmod:1352][DEBUG   ][4917] output: 
>2023-05-19 04:42:25,690 [salt.state       :325 ][INFO    ][4917] {'gid': 100, 'groups': ['salttestgroup', 'users'], 'home': '/home/salttestuser', 'name': 'salttestuser', 'passwd': 'x', 'shell': '/usr/bin/sh', 'uid': 1001, 'fullname': 'Salt Test', 'roomnumber': '', 'workphone': '', 'homephone': '', 'other': ''}
>2023-05-19 04:42:25,691 [salt.state       :2478][INFO    ][4917] Completed state [salttestuser] at time 04:42:25.690980 (duration_in_ms=227.873)
>2023-05-19 04:42:25,691 [salt.state       :3567][DEBUG   ][4917] File /var/cache/salt/minion/accumulator/139959037669560 does not exist, no need to cleanup
>2023-05-19 04:42:25,693 [salt.loader.lazy :967 ][DEBUG   ][4917] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,693 [salt.utils.lazy  :99  ][DEBUG   ][4917] LazyLoaded state.check_result
>2023-05-19 04:42:25,693 [salt.channel.client:313 ][DEBUG   ][4917] Closing AsyncReqChannel instance
>2023-05-19 04:42:25,698 [salt.minion      :2196][INFO    ][4917] Returning information for job: 20230519024224301236
>2023-05-19 04:42:25,700 [salt.utils.event :315 ][DEBUG   ][4917] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:42:25,700 [salt.utils.event :316 ][DEBUG   ][4917] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:42:25,708 [salt.utils.event :823 ][DEBUG   ][4917] Sending event: tag = __master_req_channel_payload; data = {'cmd': '_return', 'id': 'minion', 'success': True, 'return': {'group_|-salttestgroup_|-salttestgroup_|-present': {'name': 'salttestgroup', 'changes': {'name': 'salttestgroup', 'passwd': 'x', 'gid': 1000, 'members': []}, 'result': True, 'comment': 'New group salttestgroup created', '__sls__': 'user', '__run_num__': 0, 'start_time': '04:42:25.361292', 'duration': 100.315, '__id__': 'salttestgroup'}, 'user_|-salttestuser_|-salttestuser_|-present': {'name': 'salttestuser', 'changes': {'gid': 100, 'groups': ['salttestgroup', 'users'], 'home': '/home/salttestuser', 'name': 'salttestuser', 'passwd': 'x', 'shell': '/usr/bin/sh', 'uid': 1001, 'fullname': 'Salt Test', 'roomnumber': '', 'workphone': '', 'homephone': '', 'other': ''}, 'result': True, 'comment': 'New user salttestuser created', '__sls__': 'user', '__run_num__': 1, 'start_time': '04:42:25.463107', 'duration': 227.873, '__id__': 'salttestuser'}}, 'retcode': 0, 'jid': '20230519024224301236', 'fun': 'state.highstate', 'fun_args': [], 'out': 'highstate', '_stamp': '2023-05-19T02:42:25.708384'}
>2023-05-19 04:42:25,709 [salt.transport.ipc:373 ][DEBUG   ][4917] Closing IPCMessageClient instance
>2023-05-19 04:42:25,709 [salt.minion      :2065][DEBUG   ][4917] minion return: {'success': True, 'return': {'group_|-salttestgroup_|-salttestgroup_|-present': {'name': 'salttestgroup', 'changes': {'name': 'salttestgroup', 'passwd': 'x', 'gid': 1000, 'members': []}, 'result': True, 'comment': 'New group salttestgroup created', '__sls__': 'user', '__run_num__': 0, 'start_time': '04:42:25.361292', 'duration': 100.315, '__id__': 'salttestgroup'}, 'user_|-salttestuser_|-salttestuser_|-present': {'name': 'salttestuser', 'changes': {'gid': 100, 'groups': ['salttestgroup', 'users'], 'home': '/home/salttestuser', 'name': 'salttestuser', 'passwd': 'x', 'shell': '/usr/bin/sh', 'uid': 1001, 'fullname': 'Salt Test', 'roomnumber': '', 'workphone': '', 'homephone': '', 'other': ''}, 'result': True, 'comment': 'New user salttestuser created', '__sls__': 'user', '__run_num__': 1, 'start_time': '04:42:25.463107', 'duration': 227.873, '__id__': 'salttestuser'}}, 'retcode': 0, 'jid': '20230519024224301236', 'fun': 'state.highstate', 'fun_args': []}
>2023-05-19 04:42:25,800 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,800 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded zfs.is_supported
>2023-05-19 04:42:25,868 [salt.minion      :2448][DEBUG   ][3004] Refreshing beacons.
>2023-05-19 04:42:25,874 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,874 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded config.merge
>2023-05-19 04:42:25,875 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:42:25,876 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded mine.update
>2023-05-19 04:42:25,876 [salt.utils.process:1156][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024224301236) cleaned up
>2023-05-19 04:42:25,877 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:42:25,877 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 8 seconds (randomized)
>2023-05-19 04:42:28,098 [salt.channel.client:427 ][DEBUG   ][3004] PubChannel received: {'enc': 'aes', 'load': {'fun': 'state.highstate', 'arg': [], 'tgt': '*', 'jid': '20230519024228080103', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}, 'sig': b'@\xa4U\x1d^\x88\x98R\x19f2H\xc5\xc2\x05\xe5\t\xc6A+\xc7(V \'13\x8aYa{\xb2\xaf\xb2\x92p[\xd7\x8b\xde}\x07\xd2\x86\x1c\x1a\xd0@\xe3\xa7SO\xbe\x86\xdf\x17\x04W\x14\xbedB&\x0e\xdf9{h\x1bp\xe7\x8f\x84\x82UP7\xb2[\xff/\xae\xa2Z?\xa98\xebH&HP\xcf\xd9\xf8\x04Q\\\xd0\x1b\x0c\x99A\x89\xe1\xd7\x8f\x15\x873\t\xe5"\x9d:8^\x93y\xfe\xb6\x97\xba\x8d\xae\xf9\xe2q\xb6\x95\xa9e~>R\x0bp\xb9&\x17\'\xab\n\x9fK\x98hS\xaa\x96|\xe8WE\x12<Zy\xc0jzM\xed\xea\xb7*W\xf4\x1f\xbc\xff; 6J\xc0Q\x05\x11\x88\x9e\xdc\xafN\x7f\x01+T\xd9\xf2/\x1dXL\xc4C\xe2f\x9e\x98\x13\x166\\\xe4\xa9/\x1a{\xed\x07\xb0\xe5\xc5\x8fO\x0c\x8a\xa5\xe2XE\xa8\xdb%\x82\xa3\x97\xef0d9\xb9\xa1\x96\xcb\xe8Ni\xd2[So\xa2\xac\x99\xed\x1a\xa7s\xac\xfbD\'Q\x19'}
>2023-05-19 04:42:28,099 [salt.minion      :1717][INFO    ][3004] User root Executing command state.highstate with jid 20230519024228080103
>2023-05-19 04:42:28,099 [salt.minion      :1721][DEBUG   ][3004] Command details {'fun': 'state.highstate', 'arg': [], 'tgt': '*', 'jid': '20230519024228080103', 'ret': '', 'tgt_type': 'glob', 'user': 'root'}
>2023-05-19 04:42:28,108 [salt.utils.process:1145][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024228080103) added
>2023-05-19 04:42:28,242 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,242 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded jinja.render
>2023-05-19 04:42:28,244 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,244 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded yaml.render
>2023-05-19 04:42:28,249 [salt.minion      :1917][INFO    ][5041] Starting a new job 20230519024228080103 with PID 5041
>2023-05-19 04:42:28,250 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'direct_call' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,251 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'splay' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,254 [salt.utils.lazy  :103 ][DEBUG   ][5041] Could not LazyLoad direct_call.allow_missing_func: 'direct_call.allow_missing_func' is not available.
>2023-05-19 04:42:28,261 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,261 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded state.highstate
>2023-05-19 04:42:28,263 [salt.loader.lazy :863 ][DEBUG   ][5041] Override  __grains__: <module 'salt.loaded.int.module.grains' from '/usr/lib/python3.6/site-packages/salt/modules/grains.py'>
>2023-05-19 04:42:28,264 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'grains' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,264 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded grains.get
>2023-05-19 04:42:28,265 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,265 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded config.option
>2023-05-19 04:42:28,278 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,279 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded saltutil.is_running
>2023-05-19 04:42:28,291 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,300 [salt.state       :769 ][DEBUG   ][5041] Gathering pillar data for state run
>2023-05-19 04:42:28,300 [salt.pillar      :71  ][DEBUG   ][5041] Determining pillar cache
>2023-05-19 04:42:28,300 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,516 [salt.crypt       :208 ][DEBUG   ][5041] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:42:28,516 [salt.crypt       :900 ][DEBUG   ][5041] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:42:28,524 [salt.crypt       :255 ][DEBUG   ][5041] salt.crypt.verify_signature: Loading public key
>2023-05-19 04:42:28,524 [salt.crypt       :216 ][DEBUG   ][5041] salt.crypt.get_rsa_pub_key: Loading public key
>2023-05-19 04:42:28,524 [salt.crypt       :257 ][DEBUG   ][5041] salt.crypt.verify_signature: Verifying signature
>2023-05-19 04:42:28,525 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,526 [salt.state       :784 ][DEBUG   ][5041] Finished gathering pillar data for state run
>2023-05-19 04:42:28,526 [salt.state       :1274][INFO    ][5041] Loading fresh modules for state activity
>2023-05-19 04:42:28,581 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,581 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded jinja.render
>2023-05-19 04:42:28,582 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,582 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded yaml.render
>2023-05-19 04:42:28,597 [salt.fileclient  :1196][DEBUG   ][5041] In saltenv 'base', looking at rel_path 'top.sls' to resolve 'salt://top.sls'
>2023-05-19 04:42:28,598 [salt.fileclient  :1205][DEBUG   ][5041] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/top.sls' to resolve 'salt://top.sls'
>2023-05-19 04:42:28,598 [salt.fileclient  :1218][DEBUG   ][5041] Fetching file from saltenv 'base', ** attempting ** 'salt://top.sls'
>2023-05-19 04:42:28,598 [salt.fileclient  :1246][DEBUG   ][5041] No dest file found
>2023-05-19 04:42:28,620 [salt.fileclient  :1333][INFO    ][5041] Fetching file from saltenv 'base', ** done ** 'top.sls'
>2023-05-19 04:42:28,620 [salt.template    :53  ][DEBUG   ][5041] compile template: /var/cache/salt/minion/files/base/top.sls
>2023-05-19 04:42:28,620 [salt.utils.jinja :80  ][DEBUG   ][5041] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:42:28,626 [salt.template    :23  ][PROFILE ][5041] Time (in seconds) to render '/var/cache/salt/minion/files/base/top.sls' using 'jinja' renderer: 0.005303382873535156
>2023-05-19 04:42:28,626 [salt.template    :120 ][DEBUG   ][5041] Rendered data from file: /var/cache/salt/minion/files/base/top.sls:
>---
>base:
>  '*':
>    - user
>    - sysctl
>
>2023-05-19 04:42:28,627 [salt.template    :23  ][PROFILE ][5041] Time (in seconds) to render '/var/cache/salt/minion/files/base/top.sls' using 'yaml' renderer: 0.0010371208190917969
>2023-05-19 04:42:28,628 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'confirm_top' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,628 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded confirm_top.confirm_top
>2023-05-19 04:42:28,659 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'compound_match' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,660 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded compound_match.match
>2023-05-19 04:42:28,662 [salt.loaded.int.matchers.compound_match:44  ][DEBUG   ][5041] compound_match: minion ? *
>2023-05-19 04:42:28,662 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'glob_match' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,663 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded glob_match.match
>2023-05-19 04:42:28,663 [salt.loaded.int.matchers.compound_match:128 ][DEBUG   ][5041] compound_match minion ? "*" => "True"
>2023-05-19 04:42:28,663 [salt.crypt       :208 ][DEBUG   ][5041] salt.crypt.get_rsa_key: Loading private key
>2023-05-19 04:42:28,663 [salt.crypt       :900 ][DEBUG   ][5041] Loaded minion key: /etc/salt/pki/minion/minion.pem
>2023-05-19 04:42:28,682 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:28,682 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded saltutil.sync_all
>2023-05-19 04:42:28,682 [salt.loaded.int.module.saltutil:1048][DEBUG   ][5041] Syncing all
>2023-05-19 04:42:28,683 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/clouds'
>2023-05-19 04:42:28,692 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,692 [salt.utils.extmods:80  ][INFO    ][5041] Syncing clouds for environment 'base'
>2023-05-19 04:42:28,692 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_clouds, for base
>2023-05-19 04:42:28,692 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_clouds/' for environment 'base'
>2023-05-19 04:42:28,697 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_clouds'
>2023-05-19 04:42:28,697 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,699 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/beacons'
>2023-05-19 04:42:28,708 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,708 [salt.utils.extmods:80  ][INFO    ][5041] Syncing beacons for environment 'base'
>2023-05-19 04:42:28,708 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_beacons, for base
>2023-05-19 04:42:28,708 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_beacons/' for environment 'base'
>2023-05-19 04:42:28,713 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_beacons'
>2023-05-19 04:42:28,713 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,714 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/modules'
>2023-05-19 04:42:28,723 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,723 [salt.utils.extmods:80  ][INFO    ][5041] Syncing modules for environment 'base'
>2023-05-19 04:42:28,724 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_modules, for base
>2023-05-19 04:42:28,724 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_modules/' for environment 'base'
>2023-05-19 04:42:28,730 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_modules'
>2023-05-19 04:42:28,730 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,731 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/states'
>2023-05-19 04:42:28,741 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,741 [salt.utils.extmods:80  ][INFO    ][5041] Syncing states for environment 'base'
>2023-05-19 04:42:28,741 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_states, for base
>2023-05-19 04:42:28,742 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_states/' for environment 'base'
>2023-05-19 04:42:28,747 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_states'
>2023-05-19 04:42:28,747 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,749 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/sdb'
>2023-05-19 04:42:28,758 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,758 [salt.utils.extmods:80  ][INFO    ][5041] Syncing sdb for environment 'base'
>2023-05-19 04:42:28,759 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_sdb, for base
>2023-05-19 04:42:28,759 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_sdb/' for environment 'base'
>2023-05-19 04:42:28,764 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_sdb'
>2023-05-19 04:42:28,764 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,765 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/grains'
>2023-05-19 04:42:28,774 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,774 [salt.utils.extmods:80  ][INFO    ][5041] Syncing grains for environment 'base'
>2023-05-19 04:42:28,774 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_grains, for base
>2023-05-19 04:42:28,775 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_grains/' for environment 'base'
>2023-05-19 04:42:28,779 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_grains'
>2023-05-19 04:42:28,779 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,780 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/renderers'
>2023-05-19 04:42:28,789 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,789 [salt.utils.extmods:80  ][INFO    ][5041] Syncing renderers for environment 'base'
>2023-05-19 04:42:28,789 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_renderers, for base
>2023-05-19 04:42:28,789 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_renderers/' for environment 'base'
>2023-05-19 04:42:28,795 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_renderers'
>2023-05-19 04:42:28,795 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,796 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/returners'
>2023-05-19 04:42:28,805 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,805 [salt.utils.extmods:80  ][INFO    ][5041] Syncing returners for environment 'base'
>2023-05-19 04:42:28,806 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_returners, for base
>2023-05-19 04:42:28,806 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_returners/' for environment 'base'
>2023-05-19 04:42:28,812 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_returners'
>2023-05-19 04:42:28,812 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,813 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/output'
>2023-05-19 04:42:28,822 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,822 [salt.utils.extmods:80  ][INFO    ][5041] Syncing output for environment 'base'
>2023-05-19 04:42:28,822 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_output, for base
>2023-05-19 04:42:28,822 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_output/' for environment 'base'
>2023-05-19 04:42:28,828 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_output'
>2023-05-19 04:42:28,828 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,829 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/utils'
>2023-05-19 04:42:28,838 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,838 [salt.utils.extmods:80  ][INFO    ][5041] Syncing utils for environment 'base'
>2023-05-19 04:42:28,839 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_utils, for base
>2023-05-19 04:42:28,839 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_utils/' for environment 'base'
>2023-05-19 04:42:28,848 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_utils'
>2023-05-19 04:42:28,848 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,850 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/log_handlers'
>2023-05-19 04:42:28,858 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,859 [salt.utils.extmods:80  ][INFO    ][5041] Syncing log_handlers for environment 'base'
>2023-05-19 04:42:28,859 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_log_handlers, for base
>2023-05-19 04:42:28,859 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_log_handlers/' for environment 'base'
>2023-05-19 04:42:28,864 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_log_handlers'
>2023-05-19 04:42:28,864 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,865 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/executors'
>2023-05-19 04:42:28,874 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,874 [salt.utils.extmods:80  ][INFO    ][5041] Syncing executors for environment 'base'
>2023-05-19 04:42:28,874 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_executors, for base
>2023-05-19 04:42:28,875 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_executors/' for environment 'base'
>2023-05-19 04:42:28,883 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_executors'
>2023-05-19 04:42:28,884 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,885 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/proxy'
>2023-05-19 04:42:28,894 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,894 [salt.utils.extmods:80  ][INFO    ][5041] Syncing proxy for environment 'base'
>2023-05-19 04:42:28,894 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_proxy, for base
>2023-05-19 04:42:28,894 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_proxy/' for environment 'base'
>2023-05-19 04:42:28,900 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_proxy'
>2023-05-19 04:42:28,900 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,902 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/engines'
>2023-05-19 04:42:28,910 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,911 [salt.utils.extmods:80  ][INFO    ][5041] Syncing engines for environment 'base'
>2023-05-19 04:42:28,911 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_engines, for base
>2023-05-19 04:42:28,911 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_engines/' for environment 'base'
>2023-05-19 04:42:28,920 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_engines'
>2023-05-19 04:42:28,920 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,921 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/thorium'
>2023-05-19 04:42:28,930 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,930 [salt.utils.extmods:80  ][INFO    ][5041] Syncing thorium for environment 'base'
>2023-05-19 04:42:28,930 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_thorium, for base
>2023-05-19 04:42:28,930 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_thorium/' for environment 'base'
>2023-05-19 04:42:28,935 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_thorium'
>2023-05-19 04:42:28,935 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,936 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/serializers'
>2023-05-19 04:42:28,945 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,945 [salt.utils.extmods:80  ][INFO    ][5041] Syncing serializers for environment 'base'
>2023-05-19 04:42:28,945 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_serializers, for base
>2023-05-19 04:42:28,946 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_serializers/' for environment 'base'
>2023-05-19 04:42:28,950 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_serializers'
>2023-05-19 04:42:28,950 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,952 [salt.utils.extmods:70  ][INFO    ][5041] Creating module dir '/var/cache/salt/minion/extmods/matchers'
>2023-05-19 04:42:28,961 [salt.crypt       :506 ][DEBUG   ][5041] Initializing new AsyncAuth for ('/etc/salt/pki/minion', 'minion', 'tcp://10.0.2.101:4506')
>2023-05-19 04:42:28,961 [salt.utils.extmods:80  ][INFO    ][5041] Syncing matchers for environment 'base'
>2023-05-19 04:42:28,961 [salt.utils.extmods:82  ][INFO    ][5041] Loading cache from salt://_matchers, for base
>2023-05-19 04:42:28,961 [salt.fileclient  :241 ][INFO    ][5041] Caching directory '_matchers/' for environment 'base'
>2023-05-19 04:42:28,972 [salt.utils.extmods:96  ][DEBUG   ][5041] Local cache dir: '/var/cache/salt/minion/files/base/_matchers'
>2023-05-19 04:42:28,973 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:28,974 [salt.state       :1310][DEBUG   ][5041] Refreshing modules...
>2023-05-19 04:42:29,011 [salt.state       :1274][INFO    ][5041] Loading fresh modules for state activity
>2023-05-19 04:42:29,094 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'jinja' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,094 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded jinja.render
>2023-05-19 04:42:29,097 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'yaml' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,097 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded yaml.render
>2023-05-19 04:42:29,104 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'saltutil' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,104 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded saltutil.refresh_modules
>2023-05-19 04:42:29,105 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'event' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,105 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded event.fire
>2023-05-19 04:42:29,107 [salt.utils.event :315 ][DEBUG   ][5041] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:42:29,107 [salt.utils.event :316 ][DEBUG   ][5041] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:42:29,110 [salt.utils.event :823 ][DEBUG   ][5041] Sending event: tag = module_refresh; data = {'_stamp': '2023-05-19T02:42:29.110792'}
>2023-05-19 04:42:29,116 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag 'module_refresh'
>2023-05-19 04:42:29,116 [salt.minion      :2432][DEBUG   ][3004] Refreshing modules. Notify=False
>2023-05-19 04:42:29,116 [salt.loader      :1101][DEBUG   ][3004] Grains refresh requested. Refreshing grains.
>2023-05-19 04:42:29,116 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion
>2023-05-19 04:42:29,119 [salt.transport.ipc:373 ][DEBUG   ][5041] Closing IPCMessageClient instance
>2023-05-19 04:42:29,151 [salt.fileclient  :1196][DEBUG   ][5041] In saltenv 'base', looking at rel_path 'user.sls' to resolve 'salt://user.sls'
>2023-05-19 04:42:29,151 [salt.fileclient  :1205][DEBUG   ][5041] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/user.sls' to resolve 'salt://user.sls'
>2023-05-19 04:42:29,152 [salt.template    :53  ][DEBUG   ][5041] compile template: /var/cache/salt/minion/files/base/user.sls
>2023-05-19 04:42:29,152 [salt.utils.jinja :80  ][DEBUG   ][5041] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:42:29,153 [salt.template    :23  ][PROFILE ][5041] Time (in seconds) to render '/var/cache/salt/minion/files/base/user.sls' using 'jinja' renderer: 0.0012116432189941406
>2023-05-19 04:42:29,153 [salt.template    :120 ][DEBUG   ][5041] Rendered data from file: /var/cache/salt/minion/files/base/user.sls:
>---
>salttestgroup:
>  group.present
>
>salttestuser:
>  user.present:
>    - fullname: Salt Test
>    - shell: /usr/bin/sh
>    - home: /home/salttestuser
>    - groups:
>      - salttestgroup
>
>2023-05-19 04:42:29,156 [salt.template    :23  ][PROFILE ][5041] Time (in seconds) to render '/var/cache/salt/minion/files/base/user.sls' using 'yaml' renderer: 0.002001047134399414
>2023-05-19 04:42:29,175 [salt.fileclient  :1196][DEBUG   ][5041] In saltenv 'base', looking at rel_path 'sysctl.sls' to resolve 'salt://sysctl.sls'
>2023-05-19 04:42:29,176 [salt.fileclient  :1205][DEBUG   ][5041] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/sysctl.sls' to resolve 'salt://sysctl.sls'
>2023-05-19 04:42:29,176 [salt.fileclient  :1218][DEBUG   ][5041] Fetching file from saltenv 'base', ** attempting ** 'salt://sysctl.sls'
>2023-05-19 04:42:29,176 [salt.fileclient  :1246][DEBUG   ][5041] No dest file found
>2023-05-19 04:42:29,191 [salt.fileclient  :1333][INFO    ][5041] Fetching file from saltenv 'base', ** done ** 'sysctl.sls'
>2023-05-19 04:42:29,191 [salt.template    :53  ][DEBUG   ][5041] compile template: /var/cache/salt/minion/files/base/sysctl.sls
>2023-05-19 04:42:29,192 [salt.utils.jinja :80  ][DEBUG   ][5041] Jinja search path: ['/var/cache/salt/minion/files/base']
>2023-05-19 04:42:29,193 [salt.template    :23  ][PROFILE ][5041] Time (in seconds) to render '/var/cache/salt/minion/files/base/sysctl.sls' using 'jinja' renderer: 0.0009431838989257812
>2023-05-19 04:42:29,193 [salt.template    :120 ][DEBUG   ][5041] Rendered data from file: /var/cache/salt/minion/files/base/sysctl.sls:
>---
>net.ipv4.ip_forward:
>  sysctl.present:
>    - value: 1
>
>2023-05-19 04:42:29,194 [salt.template    :23  ][PROFILE ][5041] Time (in seconds) to render '/var/cache/salt/minion/files/base/sysctl.sls' using 'yaml' renderer: 0.0007774829864501953
>2023-05-19 04:42:29,195 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,195 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded config.option
>2023-05-19 04:42:29,196 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'group' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,196 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded group.present
>2023-05-19 04:42:29,196 [salt.state       :2269][INFO    ][5041] Running state [salttestgroup] at time 04:42:29.196679
>2023-05-19 04:42:29,197 [salt.state       :2301][INFO    ][5041] Executing state group.present for [salttestgroup]
>2023-05-19 04:42:29,214 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'group' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,214 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded group.info
>2023-05-19 04:42:29,215 [salt.state       :325 ][INFO    ][5041] Group salttestgroup is present and up to date
>2023-05-19 04:42:29,215 [salt.state       :2478][INFO    ][5041] Completed state [salttestgroup] at time 04:42:29.215557 (duration_in_ms=18.879)
>2023-05-19 04:42:29,216 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'user' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,216 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded user.present
>2023-05-19 04:42:29,216 [salt.state       :2269][INFO    ][5041] Running state [salttestuser] at time 04:42:29.216672
>2023-05-19 04:42:29,217 [salt.state       :2301][INFO    ][5041] Executing state user.present for [salttestuser]
>2023-05-19 04:42:29,254 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'shadow' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,254 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded shadow.info
>2023-05-19 04:42:29,263 [salt.config      :2180][DEBUG   ][3004] Including configuration from '/etc/salt/minion.d/_schedule.conf'
>2023-05-19 04:42:29,263 [salt.config      :2017][DEBUG   ][3004] Reading configuration from /etc/salt/minion.d/_schedule.conf
>2023-05-19 04:42:29,268 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'namecheap_users' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,270 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'user' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,271 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded user.info
>2023-05-19 04:42:29,290 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'file' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,290 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded file.gid_to_group
>2023-05-19 04:42:29,299 [salt.state       :325 ][INFO    ][5041] User salttestuser is present and up to date
>2023-05-19 04:42:29,299 [salt.state       :2478][INFO    ][5041] Completed state [salttestuser] at time 04:42:29.299651 (duration_in_ms=82.978)
>2023-05-19 04:42:29,322 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'sysctl' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,322 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded sysctl.show
>2023-05-19 04:42:29,322 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'sysctl' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,322 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded sysctl.present
>2023-05-19 04:42:29,322 [salt.state       :2269][INFO    ][5041] Running state [net.ipv4.ip_forward] at time 04:42:29.322835
>2023-05-19 04:42:29,328 [salt.state       :2301][INFO    ][5041] Executing state sysctl.present for [net.ipv4.ip_forward]
>2023-05-19 04:42:29,357 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'cmd' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,358 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded cmd.run_all
>2023-05-19 04:42:29,358 [salt.loaded.int.module.cmdmod:438 ][INFO    ][5041] Executing command /usr/sbin/sysctl in directory '/root'
>2023-05-19 04:42:29,387 [salt.loaded.int.module.cmdmod:922 ][DEBUG   ][5041] stdout: net.ipv4.ip_forward = 1
>2023-05-19 04:42:29,388 [salt.state       :325 ][INFO    ][5041] {'net.ipv4.ip_forward': 1}
>2023-05-19 04:42:29,388 [salt.state       :2478][INFO    ][5041] Completed state [net.ipv4.ip_forward] at time 04:42:29.388806 (duration_in_ms=65.971)
>2023-05-19 04:42:29,389 [salt.state       :3567][DEBUG   ][5041] File /var/cache/salt/minion/accumulator/139959037665576 does not exist, no need to cleanup
>2023-05-19 04:42:29,391 [salt.loader.lazy :967 ][DEBUG   ][5041] The functions from module 'state' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,395 [salt.utils.lazy  :99  ][DEBUG   ][5041] LazyLoaded state.check_result
>2023-05-19 04:42:29,395 [salt.channel.client:313 ][DEBUG   ][5041] Closing AsyncReqChannel instance
>2023-05-19 04:42:29,397 [salt.minion      :2196][INFO    ][5041] Returning information for job: 20230519024228080103
>2023-05-19 04:42:29,398 [salt.utils.event :315 ][DEBUG   ][5041] SaltEvent PUB socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pub.ipc
>2023-05-19 04:42:29,398 [salt.utils.event :316 ][DEBUG   ][5041] SaltEvent PULL socket URI: /var/run/salt/minion/minion_event_bbebec50e4_pull.ipc
>2023-05-19 04:42:29,409 [salt.utils.event :823 ][DEBUG   ][5041] Sending event: tag = __master_req_channel_payload; data = {'cmd': '_return', 'id': 'minion', 'success': True, 'return': {'group_|-salttestgroup_|-salttestgroup_|-present': {'name': 'salttestgroup', 'changes': {}, 'result': True, 'comment': 'Group salttestgroup is present and up to date', '__sls__': 'user', '__run_num__': 0, 'start_time': '04:42:29.196678', 'duration': 18.879, '__id__': 'salttestgroup'}, 'user_|-salttestuser_|-salttestuser_|-present': {'name': 'salttestuser', 'changes': {}, 'result': True, 'comment': 'User salttestuser is present and up to date', '__sls__': 'user', '__run_num__': 1, 'start_time': '04:42:29.216673', 'duration': 82.978, '__id__': 'salttestuser'}, 'sysctl_|-net.ipv4.ip_forward_|-net.ipv4.ip_forward_|-present': {'name': 'net.ipv4.ip_forward', 'result': True, 'changes': {'net.ipv4.ip_forward': 1}, 'comment': 'Updated sysctl value net.ipv4.ip_forward = 1', '__sls__': 'sysctl', '__run_num__': 2, 'start_time': '04:42:29.322835', 'duration': 65.971, '__id__': 'net.ipv4.ip_forward'}}, 'retcode': 0, 'jid': '20230519024228080103', 'fun': 'state.highstate', 'fun_args': [], 'out': 'highstate', '_stamp': '2023-05-19T02:42:29.409334'}
>2023-05-19 04:42:29,411 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'core' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,412 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'disks' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,413 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'extra' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,418 [salt.transport.ipc:373 ][DEBUG   ][5041] Closing IPCMessageClient instance
>2023-05-19 04:42:29,418 [salt.minion      :2065][DEBUG   ][5041] minion return: {'success': True, 'return': {'group_|-salttestgroup_|-salttestgroup_|-present': {'name': 'salttestgroup', 'changes': {}, 'result': True, 'comment': 'Group salttestgroup is present and up to date', '__sls__': 'user', '__run_num__': 0, 'start_time': '04:42:29.196678', 'duration': 18.879, '__id__': 'salttestgroup'}, 'user_|-salttestuser_|-salttestuser_|-present': {'name': 'salttestuser', 'changes': {}, 'result': True, 'comment': 'User salttestuser is present and up to date', '__sls__': 'user', '__run_num__': 1, 'start_time': '04:42:29.216673', 'duration': 82.978, '__id__': 'salttestuser'}, 'sysctl_|-net.ipv4.ip_forward_|-net.ipv4.ip_forward_|-present': {'name': 'net.ipv4.ip_forward', 'result': True, 'changes': {'net.ipv4.ip_forward': 1}, 'comment': 'Updated sysctl value net.ipv4.ip_forward = 1', '__sls__': 'sysctl', '__run_num__': 2, 'start_time': '04:42:29.322835', 'duration': 65.971, '__id__': 'net.ipv4.ip_forward'}}, 'retcode': 0, 'jid': '20230519024228080103', 'fun': 'state.highstate', 'fun_args': []}
>2023-05-19 04:42:29,422 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'lvm' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,423 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mdadm' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,425 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'minion_process' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,433 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'opts' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,436 [salt.loader.lazy :863 ][DEBUG   ][3004] Override  __utils__: <module 'salt.loaded.int.grains.zfs' from '/usr/lib/python3.6/site-packages/salt/grains/zfs.py'>
>2023-05-19 04:42:29,437 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,612 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'zfs' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,612 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded zfs.is_supported
>2023-05-19 04:42:29,673 [salt.minion      :2448][DEBUG   ][3004] Refreshing beacons.
>2023-05-19 04:42:29,679 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'config' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,679 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded config.merge
>2023-05-19 04:42:29,680 [salt.loader.lazy :967 ][DEBUG   ][3004] The functions from module 'mine' are being loaded by dir() on the loaded module
>2023-05-19 04:42:29,680 [salt.utils.lazy  :99  ][DEBUG   ][3004] LazyLoaded mine.update
>2023-05-19 04:42:29,681 [salt.utils.process:1156][DEBUG   ][3004] Subprocess ProcessPayload(jid=20230519024228080103) cleaned up
>2023-05-19 04:42:29,681 [salt.minion      :2696][DEBUG   ][3004] Minion of '10.0.2.101' is handling event tag '__master_req_channel_payload'
>2023-05-19 04:42:29,682 [salt.minion      :888 ][DEBUG   ][3004] Minion return retry timer set to 8 seconds (randomized)

Actions: View

Attachments on bug 1211591: 867146