{"level":"warn","ts":"2026-07-08T03:29:52.085272Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T03:29:52.085366Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T03:29:52.085612Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T03:29:52.085629Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-08T03:29:52.085799Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T03:29:52.086567Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-08T03:29:52.086736Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T03:29:52.09162Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.562701ms"} {"level":"info","ts":"2026-07-08T03:29:52.09751Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-08T03:29:52.097575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T03:29:52.097684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-08T03:29:52.097761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T03:29:52.09778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-08T03:29:52.097881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-08T03:29:52.105142Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T03:29:52.106819Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T03:29:52.107909Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-08T03:29:52.111934Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T03:29:52.112154Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T03:29:52.112263Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T03:29:52.112277Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T03:29:52.112256Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T03:29:52.115467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-08T03:29:52.116097Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-08T03:29:52.117797Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T03:29:52.117919Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-08T03:29:52.117946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-08T03:29:52.117966Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T03:29:52.117986Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T03:29:53.098219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T03:29:53.098328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T03:29:53.098349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-08T03:29:53.098367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-08T03:29:53.098379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-08T03:29:53.098395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-08T03:29:53.098408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-08T03:29:53.099467Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T03:29:53.099484Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:29:53.099654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T03:29:53.099805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T03:29:53.099972Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T03:29:53.100005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T03:29:53.100865Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:29:53.101691Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:29:53.101735Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:29:53.102986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T03:29:53.105182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"warn","ts":"2026-07-08T03:29:57.774217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.540729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:29:57.774369Z","caller":"traceutil/trace.go:171","msg":"trace[1249834208] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"166.115448ms","start":"2026-07-08T03:29:57.608231Z","end":"2026-07-08T03:29:57.774347Z","steps":["trace[1249834208] 'read index received' (duration: 41.940384ms)","trace[1249834208] 'applied index is now lower than readState.Index' (duration: 124.173974ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:29:57.774406Z","caller":"traceutil/trace.go:171","msg":"trace[1502586346] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"166.727761ms","start":"2026-07-08T03:29:57.607644Z","end":"2026-07-08T03:29:57.774372Z","steps":["trace[1502586346] 'process raft request' (duration: 42.566908ms)","trace[1502586346] 'compare' (duration: 123.412156ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:29:57.774535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.286932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-08T03:29:57.774573Z","caller":"traceutil/trace.go:171","msg":"trace[1241224245] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:285; }","duration":"166.358763ms","start":"2026-07-08T03:29:57.608204Z","end":"2026-07-08T03:29:57.774563Z","steps":["trace[1241224245] 'agreement among raft nodes before linearized reading' (duration: 166.24418ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.922282Z","caller":"traceutil/trace.go:171","msg":"trace[514729755] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"208.447731ms","start":"2026-07-08T03:29:57.713806Z","end":"2026-07-08T03:29:57.922253Z","steps":["trace[514729755] 'process raft request' (duration: 184.160157ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.934574Z","caller":"traceutil/trace.go:171","msg":"trace[1043607981] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"202.419241ms","start":"2026-07-08T03:29:57.732118Z","end":"2026-07-08T03:29:57.934537Z","steps":["trace[1043607981] 'process raft request' (duration: 202.205356ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.934739Z","caller":"traceutil/trace.go:171","msg":"trace[965165492] transaction","detail":"{read_only:false; number_of_response:0; response_revision:289; }","duration":"200.354612ms","start":"2026-07-08T03:29:57.73437Z","end":"2026-07-08T03:29:57.934725Z","steps":["trace[965165492] 'process raft request' (duration: 200.180648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:57.934776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.457108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:29:57.934817Z","caller":"traceutil/trace.go:171","msg":"trace[1398160664] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:290; }","duration":"325.528139ms","start":"2026-07-08T03:29:57.609278Z","end":"2026-07-08T03:29:57.934806Z","steps":["trace[1398160664] 'agreement among raft nodes before linearized reading' (duration: 325.429737ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.934863Z","caller":"traceutil/trace.go:171","msg":"trace[2132533590] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"202.475752ms","start":"2026-07-08T03:29:57.732377Z","end":"2026-07-08T03:29:57.934852Z","steps":["trace[2132533590] 'process raft request' (duration: 202.058852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:57.934866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.609271Z","time spent":"325.585471ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-07-08T03:29:57.934956Z","caller":"traceutil/trace.go:171","msg":"trace[840394199] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"202.144484ms","start":"2026-07-08T03:29:57.732804Z","end":"2026-07-08T03:29:57.934949Z","steps":["trace[840394199] 'process raft request' (duration: 201.668743ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.93504Z","caller":"traceutil/trace.go:171","msg":"trace[1376665968] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"201.748835ms","start":"2026-07-08T03:29:57.733282Z","end":"2026-07-08T03:29:57.935031Z","steps":["trace[1376665968] 'process raft request' (duration: 201.223553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:57.935108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.730451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"warn","ts":"2026-07-08T03:29:57.935093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.353267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-08T03:29:57.935148Z","caller":"traceutil/trace.go:171","msg":"trace[160182408] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:290; }","duration":"321.770682ms","start":"2026-07-08T03:29:57.613368Z","end":"2026-07-08T03:29:57.935138Z","steps":["trace[160182408] 'agreement among raft nodes before linearized reading' (duration: 321.70367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:57.935183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.613347Z","time spent":"321.827624ms","remote":"127.0.0.1:46782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":137,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-08T03:29:57.935182Z","caller":"traceutil/trace.go:171","msg":"trace[349545626] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:290; }","duration":"282.479379ms","start":"2026-07-08T03:29:57.652678Z","end":"2026-07-08T03:29:57.935157Z","steps":["trace[349545626] 'agreement among raft nodes before linearized reading' (duration: 282.294875ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.935305Z","caller":"traceutil/trace.go:171","msg":"trace[1510073777] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"157.292743ms","start":"2026-07-08T03:29:57.777987Z","end":"2026-07-08T03:29:57.935279Z","steps":["trace[1510073777] 'process raft request' (duration: 156.593277ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:57.934662Z","caller":"traceutil/trace.go:171","msg":"trace[64909122] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:291; }","duration":"160.21919ms","start":"2026-07-08T03:29:57.774421Z","end":"2026-07-08T03:29:57.93464Z","steps":["trace[64909122] 'read index received' (duration: 123.700952ms)","trace[64909122] 'applied index is now lower than readState.Index' (duration: 36.517358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:29:58.248731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.628854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:29:58.248846Z","caller":"traceutil/trace.go:171","msg":"trace[1834097589] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"311.021217ms","start":"2026-07-08T03:29:57.937808Z","end":"2026-07-08T03:29:58.248829Z","steps":["trace[1834097589] 'read index received' (duration: 129.181028ms)","trace[1834097589] 'applied index is now lower than readState.Index' (duration: 181.839088ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:29:58.248917Z","caller":"traceutil/trace.go:171","msg":"trace[1395920677] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"311.676071ms","start":"2026-07-08T03:29:57.937196Z","end":"2026-07-08T03:29:58.248873Z","steps":["trace[1395920677] 'process raft request' (duration: 129.679519ms)","trace[1395920677] 'compare' (duration: 181.504621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:29:58.249171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.937181Z","time spent":"311.850056ms","remote":"127.0.0.1:46782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:29:58.248971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.15776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-07-08T03:29:58.249582Z","caller":"traceutil/trace.go:171","msg":"trace[468363100] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:291; }","duration":"311.773224ms","start":"2026-07-08T03:29:57.937777Z","end":"2026-07-08T03:29:58.24955Z","steps":["trace[468363100] 'agreement among raft nodes before linearized reading' (duration: 311.127689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:58.249714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.937763Z","time spent":"311.913407ms","remote":"127.0.0.1:46902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5919,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-08T03:29:58.2986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.299472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-07-08T03:29:58.29863Z","caller":"traceutil/trace.go:171","msg":"trace[1046689187] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"355.395068ms","start":"2026-07-08T03:29:57.943213Z","end":"2026-07-08T03:29:58.298608Z","steps":["trace[1046689187] 'process raft request' (duration: 355.276155ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:58.298656Z","caller":"traceutil/trace.go:171","msg":"trace[275507391] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"360.390234ms","start":"2026-07-08T03:29:57.938253Z","end":"2026-07-08T03:29:58.298644Z","steps":["trace[275507391] 'agreement among raft nodes before linearized reading' (duration: 360.277421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:58.298703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.938238Z","time spent":"360.457455ms","remote":"127.0.0.1:46894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-08T03:29:58.298719Z","caller":"traceutil/trace.go:171","msg":"trace[1291532855] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"359.219246ms","start":"2026-07-08T03:29:57.939483Z","end":"2026-07-08T03:29:58.298702Z","steps":["trace[1291532855] 'process raft request' (duration: 358.913089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:58.298742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.943192Z","time spent":"355.49049ms","remote":"127.0.0.1:46922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T03:29:58.298886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.939468Z","time spent":"359.289559ms","remote":"127.0.0.1:46800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T03:29:58.298941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.991139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-07-08T03:29:58.299025Z","caller":"traceutil/trace.go:171","msg":"trace[686290927] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"355.097951ms","start":"2026-07-08T03:29:57.943912Z","end":"2026-07-08T03:29:58.29901Z","steps":["trace[686290927] 'agreement among raft nodes before linearized reading' (duration: 354.929208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:58.299106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:57.943902Z","time spent":"355.194743ms","remote":"127.0.0.1:46894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-08T03:29:58.5557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.594898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:29:58.555781Z","caller":"traceutil/trace.go:171","msg":"trace[387619673] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"254.323458ms","start":"2026-07-08T03:29:58.301441Z","end":"2026-07-08T03:29:58.555764Z","steps":["trace[387619673] 'read index received' (duration: 90.653279ms)","trace[387619673] 'applied index is now lower than readState.Index' (duration: 163.668339ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:29:58.555846Z","caller":"traceutil/trace.go:171","msg":"trace[1015064130] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"304.726039ms","start":"2026-07-08T03:29:58.251109Z","end":"2026-07-08T03:29:58.555835Z","steps":["trace[1015064130] 'process raft request' (duration: 140.945327ms)","trace[1015064130] 'compare' (duration: 163.238649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:29:58.555893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:29:58.251081Z","time spent":"304.789511ms","remote":"127.0.0.1:46916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T03:29:58.556148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.695147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-08T03:29:58.556229Z","caller":"traceutil/trace.go:171","msg":"trace[1581888876] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:294; }","duration":"254.810619ms","start":"2026-07-08T03:29:58.301407Z","end":"2026-07-08T03:29:58.556218Z","steps":["trace[1581888876] 'agreement among raft nodes before linearized reading' (duration: 254.640525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:58.55681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.590424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T03:29:58.556866Z","caller":"traceutil/trace.go:171","msg":"trace[526409152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:297; }","duration":"109.692076ms","start":"2026-07-08T03:29:58.447161Z","end":"2026-07-08T03:29:58.556853Z","steps":["trace[526409152] 'agreement among raft nodes before linearized reading' (duration: 109.578414ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:58.557016Z","caller":"traceutil/trace.go:171","msg":"trace[2069786298] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"254.626065ms","start":"2026-07-08T03:29:58.30238Z","end":"2026-07-08T03:29:58.557006Z","steps":["trace[2069786298] 'process raft request' (duration: 254.182045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:29:58.557206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.936526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T03:29:58.557231Z","caller":"traceutil/trace.go:171","msg":"trace[787578375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:297; }","duration":"107.985937ms","start":"2026-07-08T03:29:58.449238Z","end":"2026-07-08T03:29:58.557224Z","steps":["trace[787578375] 'agreement among raft nodes before linearized reading' (duration: 107.943866ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:58.557267Z","caller":"traceutil/trace.go:171","msg":"trace[2021731234] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"254.149894ms","start":"2026-07-08T03:29:58.303082Z","end":"2026-07-08T03:29:58.557232Z","steps":["trace[2021731234] 'process raft request' (duration: 253.571221ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:29:58.557341Z","caller":"traceutil/trace.go:171","msg":"trace[928938256] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"248.221357ms","start":"2026-07-08T03:29:58.30898Z","end":"2026-07-08T03:29:58.557202Z","steps":["trace[928938256] 'process raft request' (duration: 247.709876ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:30:26.925303Z","caller":"traceutil/trace.go:171","msg":"trace[1214921449] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"101.821885ms","start":"2026-07-08T03:30:26.823459Z","end":"2026-07-08T03:30:26.925281Z","steps":["trace[1214921449] 'process raft request' (duration: 101.736213ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:30:26.925325Z","caller":"traceutil/trace.go:171","msg":"trace[1684423897] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"102.795003ms","start":"2026-07-08T03:30:26.822492Z","end":"2026-07-08T03:30:26.925287Z","steps":["trace[1684423897] 'process raft request' (duration: 25.229144ms)","trace[1684423897] 'compare' (duration: 77.299384ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:30:36.920413Z","caller":"traceutil/trace.go:171","msg":"trace[724277638] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"153.462835ms","start":"2026-07-08T03:30:36.766922Z","end":"2026-07-08T03:30:36.920385Z","steps":["trace[724277638] 'process raft request' (duration: 153.235831ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:30:36.920733Z","caller":"traceutil/trace.go:171","msg":"trace[906948246] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:716; }","duration":"132.717898ms","start":"2026-07-08T03:30:36.787995Z","end":"2026-07-08T03:30:36.920712Z","steps":["trace[906948246] 'read index received' (duration: 132.125537ms)","trace[906948246] 'applied index is now lower than readState.Index' (duration: 591.491µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:30:36.92102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.898131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T03:30:36.921112Z","caller":"traceutil/trace.go:171","msg":"trace[784869041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"129.022772ms","start":"2026-07-08T03:30:36.792071Z","end":"2026-07-08T03:30:36.921093Z","steps":["trace[784869041] 'agreement among raft nodes before linearized reading' (duration: 128.834469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:30:36.921121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.127215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T03:30:36.921438Z","caller":"traceutil/trace.go:171","msg":"trace[1192997468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"133.479851ms","start":"2026-07-08T03:30:36.787941Z","end":"2026-07-08T03:30:36.921421Z","steps":["trace[1192997468] 'agreement among raft nodes before linearized reading' (duration: 133.102415ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:30:36.920936Z","caller":"traceutil/trace.go:171","msg":"trace[1095076128] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"150.783997ms","start":"2026-07-08T03:30:36.770126Z","end":"2026-07-08T03:30:36.92091Z","steps":["trace[1095076128] 'process raft request' (duration: 150.37083ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:30:53.234456Z","caller":"traceutil/trace.go:171","msg":"trace[255397961] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"248.421886ms","start":"2026-07-08T03:30:52.986009Z","end":"2026-07-08T03:30:53.234431Z","steps":["trace[255397961] 'process raft request' (duration: 206.803243ms)","trace[255397961] 'compare' (duration: 41.43616ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:30:53.276327Z","caller":"traceutil/trace.go:171","msg":"trace[1955757072] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:834; }","duration":"283.003463ms","start":"2026-07-08T03:30:52.993292Z","end":"2026-07-08T03:30:53.276295Z","steps":["trace[1955757072] 'read index received' (duration: 199.575545ms)","trace[1955757072] 'applied index is now lower than readState.Index' (duration: 83.426858ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:30:53.276451Z","caller":"traceutil/trace.go:171","msg":"trace[1956148609] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"288.063786ms","start":"2026-07-08T03:30:52.988377Z","end":"2026-07-08T03:30:53.276441Z","steps":["trace[1956148609] 'process raft request' (duration: 287.7132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:30:53.276647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.768129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T03:30:53.276759Z","caller":"traceutil/trace.go:171","msg":"trace[460985514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"178.921812ms","start":"2026-07-08T03:30:53.097818Z","end":"2026-07-08T03:30:53.27674Z","steps":["trace[460985514] 'agreement among raft nodes before linearized reading' (duration: 178.709658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:30:53.276813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.504361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T03:30:53.276895Z","caller":"traceutil/trace.go:171","msg":"trace[1735045479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"283.617994ms","start":"2026-07-08T03:30:52.993256Z","end":"2026-07-08T03:30:53.276874Z","steps":["trace[1735045479] 'agreement among raft nodes before linearized reading' (duration: 283.347689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:32:06.050972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.281354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:32:06.05114Z","caller":"traceutil/trace.go:171","msg":"trace[958049400] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"350.669209ms","start":"2026-07-08T03:32:05.700453Z","end":"2026-07-08T03:32:06.051122Z","steps":["trace[958049400] 'process raft request' (duration: 25.142922ms)","trace[958049400] 'compare' (duration: 325.169032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:32:06.051341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:32:05.700431Z","time spent":"350.884291ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:32:06.051361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.684463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T03:32:06.051423Z","caller":"traceutil/trace.go:171","msg":"trace[1082100144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"319.788163ms","start":"2026-07-08T03:32:05.731624Z","end":"2026-07-08T03:32:06.051412Z","steps":["trace[1082100144] 'agreement among raft nodes before linearized reading' (duration: 319.620341ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:32:06.051275Z","caller":"traceutil/trace.go:171","msg":"trace[2048554660] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"350.534337ms","start":"2026-07-08T03:32:05.700732Z","end":"2026-07-08T03:32:06.051266Z","steps":["trace[2048554660] 'process raft request' (duration: 350.344274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:32:06.051461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:32:05.731607Z","time spent":"319.846835ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T03:32:06.051141Z","caller":"traceutil/trace.go:171","msg":"trace[455393628] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1938; }","duration":"319.44451ms","start":"2026-07-08T03:32:05.731679Z","end":"2026-07-08T03:32:06.051123Z","steps":["trace[455393628] 'read index received' (duration: 91.938725ms)","trace[455393628] 'applied index is now lower than readState.Index' (duration: 227.504875ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:32:06.051669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.340188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ef304eeb-492b-4c99-a408-717f8994db58\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-08T03:32:06.051721Z","caller":"traceutil/trace.go:171","msg":"trace[1784708232] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ef304eeb-492b-4c99-a408-717f8994db58; range_end:; response_count:1; response_revision:1870; }","duration":"254.439049ms","start":"2026-07-08T03:32:05.797273Z","end":"2026-07-08T03:32:06.051712Z","steps":["trace[1784708232] 'agreement among raft nodes before linearized reading' (duration: 254.345108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:32:06.051706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:32:05.700713Z","time spent":"350.78553ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:32:06.425817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.057919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:32:06.425957Z","caller":"traceutil/trace.go:171","msg":"trace[1840228575] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"174.308723ms","start":"2026-07-08T03:32:06.251626Z","end":"2026-07-08T03:32:06.425934Z","steps":["trace[1840228575] 'read index received' (duration: 62.038112ms)","trace[1840228575] 'applied index is now lower than readState.Index' (duration: 112.268841ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:32:06.426002Z","caller":"traceutil/trace.go:171","msg":"trace[1592229138] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"217.124549ms","start":"2026-07-08T03:32:06.208844Z","end":"2026-07-08T03:32:06.425969Z","steps":["trace[1592229138] 'process raft request' (duration: 104.794216ms)","trace[1592229138] 'compare' (duration: 111.915807ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:32:06.426113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.479825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:32:06.426243Z","caller":"traceutil/trace.go:171","msg":"trace[189797730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"174.641457ms","start":"2026-07-08T03:32:06.251589Z","end":"2026-07-08T03:32:06.42623Z","steps":["trace[189797730] 'agreement among raft nodes before linearized reading' (duration: 174.464355ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:32:21.817581Z","caller":"traceutil/trace.go:171","msg":"trace[1800688803] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"105.806024ms","start":"2026-07-08T03:32:21.711748Z","end":"2026-07-08T03:32:21.817554Z","steps":["trace[1800688803] 'process raft request' (duration: 105.530061ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:32:41.464133Z","caller":"traceutil/trace.go:171","msg":"trace[1635990151] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"187.062731ms","start":"2026-07-08T03:32:41.277047Z","end":"2026-07-08T03:32:41.46411Z","steps":["trace[1635990151] 'process raft request' (duration: 117.950766ms)","trace[1635990151] 'compare' (duration: 69.016603ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:32:41.470106Z","caller":"traceutil/trace.go:171","msg":"trace[1736253224] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"132.258698ms","start":"2026-07-08T03:32:41.337829Z","end":"2026-07-08T03:32:41.470087Z","steps":["trace[1736253224] 'process raft request' (duration: 132.160476ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:34:08.565711Z","caller":"traceutil/trace.go:171","msg":"trace[592716111] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"135.891062ms","start":"2026-07-08T03:34:08.429796Z","end":"2026-07-08T03:34:08.565687Z","steps":["trace[592716111] 'process raft request' (duration: 125.377431ms)","trace[592716111] 'compare' (duration: 10.408567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:34:08.910551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.164252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:34:08.910642Z","caller":"traceutil/trace.go:171","msg":"trace[1893150156] linearizableReadLoop","detail":"{readStateIndex:2795; appliedIndex:2794; }","duration":"244.949397ms","start":"2026-07-08T03:34:08.665679Z","end":"2026-07-08T03:34:08.910629Z","steps":["trace[1893150156] 'read index received' (duration: 55.583846ms)","trace[1893150156] 'applied index is now lower than readState.Index' (duration: 189.364471ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:34:08.910668Z","caller":"traceutil/trace.go:171","msg":"trace[1394272831] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"248.204975ms","start":"2026-07-08T03:34:08.662434Z","end":"2026-07-08T03:34:08.910639Z","steps":["trace[1394272831] 'process raft request' (duration: 58.886426ms)","trace[1394272831] 'compare' (duration: 189.057917ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:34:08.910863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.91968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T03:34:08.910925Z","caller":"traceutil/trace.go:171","msg":"trace[2078416500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2696; }","duration":"244.014814ms","start":"2026-07-08T03:34:08.666896Z","end":"2026-07-08T03:34:08.910911Z","steps":["trace[2078416500] 'agreement among raft nodes before linearized reading' (duration: 243.843046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:34:08.911256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.573255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T03:34:08.911288Z","caller":"traceutil/trace.go:171","msg":"trace[1504010843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2696; }","duration":"245.689411ms","start":"2026-07-08T03:34:08.66559Z","end":"2026-07-08T03:34:08.91128Z","steps":["trace[1504010843] 'agreement among raft nodes before linearized reading' (duration: 245.105244ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:37:44.213739Z","caller":"traceutil/trace.go:171","msg":"trace[1856733748] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"103.618817ms","start":"2026-07-08T03:37:44.110098Z","end":"2026-07-08T03:37:44.213717Z","steps":["trace[1856733748] 'process raft request' (duration: 55.128806ms)","trace[1856733748] 'compare' (duration: 48.226376ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:37:53.889619Z","caller":"traceutil/trace.go:171","msg":"trace[60576388] linearizableReadLoop","detail":"{readStateIndex:4827; appliedIndex:4826; }","duration":"366.081334ms","start":"2026-07-08T03:37:53.52351Z","end":"2026-07-08T03:37:53.889592Z","steps":["trace[60576388] 'read index received' (duration: 365.696896ms)","trace[60576388] 'applied index is now lower than readState.Index' (duration: 382.738µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:37:53.889704Z","caller":"traceutil/trace.go:171","msg":"trace[30851894] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"375.686869ms","start":"2026-07-08T03:37:53.513978Z","end":"2026-07-08T03:37:53.889665Z","steps":["trace[30851894] 'process raft request' (duration: 375.203648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:53.88993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:37:53.513968Z","time spent":"375.828193ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:37:53.889951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.425781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-08T03:37:53.890002Z","caller":"traceutil/trace.go:171","msg":"trace[286422976] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4652; }","duration":"366.517024ms","start":"2026-07-08T03:37:53.523468Z","end":"2026-07-08T03:37:53.889985Z","steps":["trace[286422976] 'agreement among raft nodes before linearized reading' (duration: 366.254758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:53.890038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:37:53.52345Z","time spent":"366.580094ms","remote":"127.0.0.1:46822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3528,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-07-08T03:37:53.90113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.50026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-08T03:37:53.901183Z","caller":"traceutil/trace.go:171","msg":"trace[757915701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4653; }","duration":"129.611713ms","start":"2026-07-08T03:37:53.771556Z","end":"2026-07-08T03:37:53.901168Z","steps":["trace[757915701] 'agreement among raft nodes before linearized reading' (duration: 129.4691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:53.901366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.186001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T03:37:53.901403Z","caller":"traceutil/trace.go:171","msg":"trace[2064186931] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4653; }","duration":"248.261672ms","start":"2026-07-08T03:37:53.653131Z","end":"2026-07-08T03:37:53.901393Z","steps":["trace[2064186931] 'agreement among raft nodes before linearized reading' (duration: 248.195531ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:38:06.763846Z","caller":"traceutil/trace.go:171","msg":"trace[679221455] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"108.44729ms","start":"2026-07-08T03:38:06.655377Z","end":"2026-07-08T03:38:06.763825Z","steps":["trace[679221455] 'process raft request' (duration: 108.318837ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:38:06.831895Z","caller":"traceutil/trace.go:171","msg":"trace[470795368] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"123.82151ms","start":"2026-07-08T03:38:06.708046Z","end":"2026-07-08T03:38:06.831867Z","steps":["trace[470795368] 'process raft request' (duration: 123.697507ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:38:55.135965Z","caller":"traceutil/trace.go:171","msg":"trace[604817309] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"113.57215ms","start":"2026-07-08T03:38:55.022356Z","end":"2026-07-08T03:38:55.135929Z","steps":["trace[604817309] 'process raft request' (duration: 113.342006ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:39:53.127967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2928} {"level":"info","ts":"2026-07-08T03:39:53.243284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2928,"took":"113.781465ms","hash":1896406183,"current-db-size-bytes":54620160,"current-db-size":"55 MB","current-db-size-in-use-bytes":29777920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T03:39:53.243339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1896406183,"revision":2928,"compact-revision":-1} {"level":"info","ts":"2026-07-08T03:44:53.134294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5653} {"level":"info","ts":"2026-07-08T03:44:53.232476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5653,"took":"96.656358ms","hash":2022193582,"current-db-size-bytes":54620160,"current-db-size":"55 MB","current-db-size-in-use-bytes":18186240,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-08T03:44:53.232542Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2022193582,"revision":5653,"compact-revision":2928}