{"level":"warn","ts":"2026-04-09T02:05:37.989719Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:05:37.989827Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-04-09T02:05:37.989908Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:05:37.989927Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-09T02:05:37.989947Z","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-04-09T02:05:37.990648Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-09T02:05:37.990735Z","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":16,"max-cpu-available":16,"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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-04-09T02:05:37.999157Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.115267ms"} {"level":"info","ts":"2026-04-09T02:05:38.010412Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-09T02:05:38.01051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:05:38.010534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-09T02:05:38.010558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:05:38.010579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-09T02:05:38.01063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-09T02:05:38.018999Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:05:38.021469Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:05:38.022866Z","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-04-09T02:05:38.025363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:05:38.025682Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:05:38.025951Z","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-04-09T02:05:38.026153Z","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-04-09T02:05:38.026202Z","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-04-09T02:05:38.028493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-09T02:05:38.028748Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-09T02:05:38.030305Z","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-04-09T02:05:38.030435Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-09T02:05:38.030533Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-09T02:05:38.030696Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:05:38.03081Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:05:38.711817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:05:38.711898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:05:38.711931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-09T02:05:38.711955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:05:38.711967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-09T02:05:38.711988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-09T02:05:38.712003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-09T02:05:38.713212Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:05:38.713215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:05:38.713251Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:05:38.713301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:05:38.713586Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:05:38.713626Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:05:38.714757Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:05:38.71497Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:05:38.715022Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:05:38.717426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-04-09T02:05:38.717606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:05:50.588347Z","caller":"traceutil/trace.go:171","msg":"trace[636194603] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"227.73348ms","start":"2026-04-09T02:05:50.360577Z","end":"2026-04-09T02:05:50.58831Z","steps":["trace[636194603] 'read index received' (duration: 227.629908ms)","trace[636194603] 'applied index is now lower than readState.Index' (duration: 102.082µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:50.588552Z","caller":"traceutil/trace.go:171","msg":"trace[539284747] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"530.471416ms","start":"2026-04-09T02:05:50.058015Z","end":"2026-04-09T02:05:50.588487Z","steps":["trace[539284747] 'process raft request' (duration: 530.053787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:50.588585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.979975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T02:05:50.588721Z","caller":"traceutil/trace.go:171","msg":"trace[389977249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:392; }","duration":"228.193399ms","start":"2026-04-09T02:05:50.360509Z","end":"2026-04-09T02:05:50.588702Z","steps":["trace[389977249] 'agreement among raft nodes before linearized reading' (duration: 227.979925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:50.5895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:50.057997Z","time spent":"530.697041ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:05:50.589966Z","caller":"traceutil/trace.go:171","msg":"trace[648215805] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"191.295854ms","start":"2026-04-09T02:05:50.398657Z","end":"2026-04-09T02:05:50.589953Z","steps":["trace[648215805] 'process raft request' (duration: 191.217012ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:54.404104Z","caller":"traceutil/trace.go:171","msg":"trace[2115992481] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"212.996291ms","start":"2026-04-09T02:05:54.191066Z","end":"2026-04-09T02:05:54.404062Z","steps":["trace[2115992481] 'process raft request' (duration: 212.782737ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:56.795107Z","caller":"traceutil/trace.go:171","msg":"trace[2075056854] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"137.015844ms","start":"2026-04-09T02:05:56.658051Z","end":"2026-04-09T02:05:56.795067Z","steps":["trace[2075056854] 'read index received' (duration: 136.743218ms)","trace[2075056854] 'applied index is now lower than readState.Index' (duration: 271.116µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:56.795152Z","caller":"traceutil/trace.go:171","msg":"trace[818534419] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"886.320007ms","start":"2026-04-09T02:05:55.908797Z","end":"2026-04-09T02:05:56.795117Z","steps":["trace[818534419] 'process raft request' (duration: 886.094502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.795376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:55.908778Z","time spent":"886.48228ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:56.795359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.270559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T02:05:56.795477Z","caller":"traceutil/trace.go:171","msg":"trace[659379680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"137.457143ms","start":"2026-04-09T02:05:56.657993Z","end":"2026-04-09T02:05:56.79545Z","steps":["trace[659379680] 'agreement among raft nodes before linearized reading' (duration: 137.208678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.795495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.057761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:05:56.79567Z","caller":"traceutil/trace.go:171","msg":"trace[1430781512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"135.272207ms","start":"2026-04-09T02:05:56.66037Z","end":"2026-04-09T02:05:56.795642Z","steps":["trace[1430781512] 'agreement among raft nodes before linearized reading' (duration: 134.97688ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:07.623388Z","caller":"traceutil/trace.go:171","msg":"trace[379681635] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"102.145563ms","start":"2026-04-09T02:06:07.521217Z","end":"2026-04-09T02:06:07.623363Z","steps":["trace[379681635] 'process raft request' (duration: 101.99978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.365981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.530168ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:18.36613Z","caller":"traceutil/trace.go:171","msg":"trace[7558657] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:529; }","duration":"126.705441ms","start":"2026-04-09T02:06:18.239405Z","end":"2026-04-09T02:06:18.366111Z","steps":["trace[7558657] 'range keys from in-memory index tree' (duration: 126.511377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.366295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.170787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:18.366391Z","caller":"traceutil/trace.go:171","msg":"trace[726442366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"573.315561ms","start":"2026-04-09T02:06:17.793054Z","end":"2026-04-09T02:06:18.366369Z","steps":["trace[726442366] 'range keys from in-memory index tree' (duration: 573.085516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.366442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.79303Z","time spent":"573.402312ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T02:06:18.370941Z","caller":"traceutil/trace.go:171","msg":"trace[1064889652] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"510.267471ms","start":"2026-04-09T02:06:17.860658Z","end":"2026-04-09T02:06:18.370925Z","steps":["trace[1064889652] 'process raft request' (duration: 510.129418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.371158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.860634Z","time spent":"510.359163ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:06:18.38207Z","caller":"traceutil/trace.go:171","msg":"trace[1103545834] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"502.044084ms","start":"2026-04-09T02:06:17.880001Z","end":"2026-04-09T02:06:18.382045Z","steps":["trace[1103545834] 'process raft request' (duration: 501.817669ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382163Z","caller":"traceutil/trace.go:171","msg":"trace[1298452310] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"502.053814ms","start":"2026-04-09T02:06:17.880056Z","end":"2026-04-09T02:06:18.38211Z","steps":["trace[1298452310] 'process raft request' (duration: 501.904391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.382203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.87998Z","time spent":"502.142145ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.382343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.880053Z","time spent":"502.197597ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:06:18.382402Z","caller":"traceutil/trace.go:171","msg":"trace[374350055] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"483.711538ms","start":"2026-04-09T02:06:17.898674Z","end":"2026-04-09T02:06:18.382386Z","steps":["trace[374350055] 'process raft request' (duration: 483.32484ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382453Z","caller":"traceutil/trace.go:171","msg":"trace[1217292451] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"480.5461ms","start":"2026-04-09T02:06:17.901843Z","end":"2026-04-09T02:06:18.382389Z","steps":["trace[1217292451] 'process raft request' (duration: 480.304735ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382473Z","caller":"traceutil/trace.go:171","msg":"trace[1236055836] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"455.353397ms","start":"2026-04-09T02:06:17.927105Z","end":"2026-04-09T02:06:18.382459Z","steps":["trace[1236055836] 'process raft request' (duration: 455.196393ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382459Z","caller":"traceutil/trace.go:171","msg":"trace[347996536] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"471.546165ms","start":"2026-04-09T02:06:17.910857Z","end":"2026-04-09T02:06:18.382403Z","steps":["trace[347996536] 'process raft request' (duration: 471.333401ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382486Z","caller":"traceutil/trace.go:171","msg":"trace[425364544] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"482.610944ms","start":"2026-04-09T02:06:17.899764Z","end":"2026-04-09T02:06:18.382375Z","steps":["trace[425364544] 'process raft request' (duration: 482.299207ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382512Z","caller":"traceutil/trace.go:171","msg":"trace[29680589] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"482.136654ms","start":"2026-04-09T02:06:17.900298Z","end":"2026-04-09T02:06:18.382435Z","steps":["trace[29680589] 'process raft request' (duration: 481.804727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.38259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.927091Z","time spent":"455.443968ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:06:18.382574Z","caller":"traceutil/trace.go:171","msg":"trace[1836836904] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"482.828019ms","start":"2026-04-09T02:06:17.899678Z","end":"2026-04-09T02:06:18.382506Z","steps":["trace[1836836904] 'process raft request' (duration: 482.356269ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.382686Z","caller":"traceutil/trace.go:171","msg":"trace[1335768874] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"338.13988ms","start":"2026-04-09T02:06:18.044528Z","end":"2026-04-09T02:06:18.382668Z","steps":["trace[1335768874] 'process raft request' (duration: 337.926026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.382685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.910841Z","time spent":"471.7306ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.382695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.89974Z","time spent":"482.831849ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:06:18.382708Z","caller":"traceutil/trace.go:171","msg":"trace[1625642944] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"443.933751ms","start":"2026-04-09T02:06:17.938674Z","end":"2026-04-09T02:06:18.382608Z","steps":["trace[1625642944] 'process raft request' (duration: 443.708676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.382742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.900264Z","time spent":"482.363589ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.382766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:18.044499Z","time spent":"338.231921ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.382826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.938657Z","time spent":"444.132005ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.382737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.899665Z","time spent":"483.006942ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.382602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.901829Z","time spent":"480.697033ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:18.383035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.898647Z","time spent":"483.899791ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:06:22.733238Z","caller":"traceutil/trace.go:171","msg":"trace[1982539212] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"156.627507ms","start":"2026-04-09T02:06:22.576568Z","end":"2026-04-09T02:06:22.733196Z","steps":["trace[1982539212] 'process raft request' (duration: 150.797351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:23.095904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.824565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:23.096081Z","caller":"traceutil/trace.go:171","msg":"trace[150211341] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"302.183045ms","start":"2026-04-09T02:06:22.793871Z","end":"2026-04-09T02:06:23.096054Z","steps":["trace[150211341] 'process raft request' (duration: 52.966332ms)","trace[150211341] 'compare' (duration: 248.536018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:23.096155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:22.79384Z","time spent":"302.290537ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:06:24.982121Z","caller":"traceutil/trace.go:171","msg":"trace[28662460] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"188.106915ms","start":"2026-04-09T02:06:24.793974Z","end":"2026-04-09T02:06:24.982081Z","steps":["trace[28662460] 'read index received' (duration: 187.046713ms)","trace[28662460] 'applied index is now lower than readState.Index' (duration: 1.058462ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:24.982239Z","caller":"traceutil/trace.go:171","msg":"trace[463820792] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"453.268571ms","start":"2026-04-09T02:06:24.528915Z","end":"2026-04-09T02:06:24.982183Z","steps":["trace[463820792] 'process raft request' (duration: 452.411193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:24.982393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.387461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:24.982529Z","caller":"traceutil/trace.go:171","msg":"trace[1410862467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"188.598265ms","start":"2026-04-09T02:06:24.793894Z","end":"2026-04-09T02:06:24.982492Z","steps":["trace[1410862467] 'agreement among raft nodes before linearized reading' (duration: 188.334659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:24.982534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:24.528876Z","time spent":"453.500486ms","remote":"127.0.0.1:33414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:25.90286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.383472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:25.902984Z","caller":"traceutil/trace.go:171","msg":"trace[2112237976] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"632.76614ms","start":"2026-04-09T02:06:25.2702Z","end":"2026-04-09T02:06:25.902966Z","steps":["trace[2112237976] 'read index received' (duration: 431.165724ms)","trace[2112237976] 'applied index is now lower than readState.Index' (duration: 201.599006ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:25.903113Z","caller":"traceutil/trace.go:171","msg":"trace[2043268595] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"863.010322ms","start":"2026-04-09T02:06:25.040027Z","end":"2026-04-09T02:06:25.903037Z","steps":["trace[2043268595] 'process raft request' (duration: 661.324115ms)","trace[2043268595] 'compare' (duration: 201.002453ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:25.903301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.039989Z","time spent":"863.223217ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:25.903361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.147818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8k66c\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T02:06:25.903391Z","caller":"traceutil/trace.go:171","msg":"trace[1944897736] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8k66c; range_end:; response_count:1; response_revision:635; }","duration":"633.228ms","start":"2026-04-09T02:06:25.270152Z","end":"2026-04-09T02:06:25.90338Z","steps":["trace[1944897736] 'agreement among raft nodes before linearized reading' (duration: 632.883942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.90345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.270129Z","time spent":"633.299001ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-8k66c\" "} {"level":"info","ts":"2026-04-09T02:06:25.981972Z","caller":"traceutil/trace.go:171","msg":"trace[1116283423] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"487.607832ms","start":"2026-04-09T02:06:25.49434Z","end":"2026-04-09T02:06:25.981948Z","steps":["trace[1116283423] 'process raft request' (duration: 487.239384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.982164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.494307Z","time spent":"487.735454ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:25.982441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.231916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:06:25.98248Z","caller":"traceutil/trace.go:171","msg":"trace[524047932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"257.309758ms","start":"2026-04-09T02:06:25.72516Z","end":"2026-04-09T02:06:25.982469Z","steps":["trace[524047932] 'agreement among raft nodes before linearized reading' (duration: 257.182615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.982616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.106645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T02:06:25.982627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.775142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-09T02:06:25.982707Z","caller":"traceutil/trace.go:171","msg":"trace[1527210166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"615.917536ms","start":"2026-04-09T02:06:25.366769Z","end":"2026-04-09T02:06:25.982687Z","steps":["trace[1527210166] 'agreement among raft nodes before linearized reading' (duration: 615.64854ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:25.982704Z","caller":"traceutil/trace.go:171","msg":"trace[1520160971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"696.229187ms","start":"2026-04-09T02:06:25.286447Z","end":"2026-04-09T02:06:25.982676Z","steps":["trace[1520160971] 'agreement among raft nodes before linearized reading' (duration: 695.949501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.982747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.366747Z","time spent":"615.991858ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:06:25.982754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.286433Z","time spent":"696.307809ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:06:25.982614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.059414ms","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-04-09T02:06:25.98284Z","caller":"traceutil/trace.go:171","msg":"trace[902663761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"696.318109ms","start":"2026-04-09T02:06:25.286502Z","end":"2026-04-09T02:06:25.98282Z","steps":["trace[902663761] 'agreement among raft nodes before linearized reading' (duration: 695.970142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.982894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.286494Z","time spent":"696.37481ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:26.48178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676844877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:06:26.982185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676844877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:06:27.144021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155842018s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T02:06:27.14453Z","caller":"traceutil/trace.go:171","msg":"trace[1088007149] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.22416844s","start":"2026-04-09T02:06:25.920336Z","end":"2026-04-09T02:06:27.144504Z","steps":["trace[1088007149] 'process raft request' (duration: 1.223920785s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:27.144737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.920307Z","time spent":"1.224327923s","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:27.681106Z","caller":"traceutil/trace.go:171","msg":"trace[1508013748] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.694333965s","start":"2026-04-09T02:06:25.986737Z","end":"2026-04-09T02:06:27.681071Z","steps":["trace[1508013748] 'process raft request' (duration: 1.667875834s)","trace[1508013748] 'compare' (duration: 26.094874ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:27.681279Z","caller":"traceutil/trace.go:171","msg":"trace[1860315178] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.694509089s","start":"2026-04-09T02:06:25.986748Z","end":"2026-04-09T02:06:27.681257Z","steps":["trace[1860315178] 'process raft request' (duration: 1.694243684s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:27.681291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.986712Z","time spent":"1.694479649s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:27.681367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.986726Z","time spent":"1.694595151s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:27.681388Z","caller":"traceutil/trace.go:171","msg":"trace[1751315249] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.693441517s","start":"2026-04-09T02:06:25.987925Z","end":"2026-04-09T02:06:27.681366Z","steps":["trace[1751315249] 'process raft request' (duration: 1.693264713s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:27.68146Z","caller":"traceutil/trace.go:171","msg":"trace[900619919] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.694386126s","start":"2026-04-09T02:06:25.987063Z","end":"2026-04-09T02:06:27.681449Z","steps":["trace[900619919] 'process raft request' (duration: 1.694007398s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:27.681493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.987915Z","time spent":"1.693523668s","remote":"127.0.0.1:33580","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-04-09T02:06:27.681507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.987052Z","time spent":"1.694428627s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:27.694867Z","caller":"traceutil/trace.go:171","msg":"trace[248496948] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:658; }","duration":"1.71311457s","start":"2026-04-09T02:06:25.981726Z","end":"2026-04-09T02:06:27.694841Z","steps":["trace[248496948] 'read index received' (duration: 1.162609602s)","trace[248496948] 'applied index is now lower than readState.Index' (duration: 550.503478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:27.695025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781526165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:06:27.695054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.989706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:27.695082Z","caller":"traceutil/trace.go:171","msg":"trace[1084804955] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:641; }","duration":"1.781597876s","start":"2026-04-09T02:06:25.913466Z","end":"2026-04-09T02:06:27.695064Z","steps":["trace[1084804955] 'agreement among raft nodes before linearized reading' (duration: 1.781491624s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:27.695142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.913375Z","time spent":"1.781750569s","remote":"127.0.0.1:33792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-04-09T02:06:27.69509Z","caller":"traceutil/trace.go:171","msg":"trace[557593170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"903.079248ms","start":"2026-04-09T02:06:26.792Z","end":"2026-04-09T02:06:27.695079Z","steps":["trace[557593170] 'agreement among raft nodes before linearized reading' (duration: 902.992947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:27.695224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:26.791978Z","time spent":"903.236332ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T02:06:33.241892Z","caller":"traceutil/trace.go:171","msg":"trace[30152604] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"131.332241ms","start":"2026-04-09T02:06:33.110426Z","end":"2026-04-09T02:06:33.241758Z","steps":["trace[30152604] 'process raft request' (duration: 123.005731ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:33.479433Z","caller":"traceutil/trace.go:171","msg":"trace[1633206812] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"130.993183ms","start":"2026-04-09T02:06:33.348414Z","end":"2026-04-09T02:06:33.479407Z","steps":["trace[1633206812] 'process raft request' (duration: 130.535584ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:35.912894Z","caller":"traceutil/trace.go:171","msg":"trace[985153349] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"110.250067ms","start":"2026-04-09T02:06:35.80259Z","end":"2026-04-09T02:06:35.91284Z","steps":["trace[985153349] 'process raft request' (duration: 68.888255ms)","trace[985153349] 'compare' (duration: 41.055725ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:35.912976Z","caller":"traceutil/trace.go:171","msg":"trace[1421827655] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"109.715276ms","start":"2026-04-09T02:06:35.803204Z","end":"2026-04-09T02:06:35.912919Z","steps":["trace[1421827655] 'process raft request' (duration: 109.530422ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:35.912931Z","caller":"traceutil/trace.go:171","msg":"trace[1589985646] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"109.335827ms","start":"2026-04-09T02:06:35.80356Z","end":"2026-04-09T02:06:35.912896Z","steps":["trace[1589985646] 'process raft request' (duration: 109.259705ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:40.517292Z","caller":"traceutil/trace.go:171","msg":"trace[1930947290] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"106.719721ms","start":"2026-04-09T02:06:40.410543Z","end":"2026-04-09T02:06:40.517262Z","steps":["trace[1930947290] 'process raft request' (duration: 106.414974ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:40.517442Z","caller":"traceutil/trace.go:171","msg":"trace[1070053863] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"104.35553ms","start":"2026-04-09T02:06:40.413053Z","end":"2026-04-09T02:06:40.517408Z","steps":["trace[1070053863] 'process raft request' (duration: 104.120725ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:40.649184Z","caller":"traceutil/trace.go:171","msg":"trace[262953886] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"121.922199ms","start":"2026-04-09T02:06:40.52723Z","end":"2026-04-09T02:06:40.649152Z","steps":["trace[262953886] 'process raft request' (duration: 121.716815ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:42.123423Z","caller":"traceutil/trace.go:171","msg":"trace[1696979286] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"176.216219ms","start":"2026-04-09T02:06:41.947164Z","end":"2026-04-09T02:06:42.123381Z","steps":["trace[1696979286] 'process raft request' (duration: 172.822106ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:42.123545Z","caller":"traceutil/trace.go:171","msg":"trace[454743014] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"175.345759ms","start":"2026-04-09T02:06:41.948185Z","end":"2026-04-09T02:06:42.123531Z","steps":["trace[454743014] 'process raft request' (duration: 175.150135ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:42.123421Z","caller":"traceutil/trace.go:171","msg":"trace[678602975] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"176.161857ms","start":"2026-04-09T02:06:41.947236Z","end":"2026-04-09T02:06:42.123397Z","steps":["trace[678602975] 'read index received' (duration: 172.926278ms)","trace[678602975] 'applied index is now lower than readState.Index' (duration: 3.234219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:42.123803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.543225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:06:42.123848Z","caller":"traceutil/trace.go:171","msg":"trace[179645720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"176.663318ms","start":"2026-04-09T02:06:41.947175Z","end":"2026-04-09T02:06:42.123838Z","steps":["trace[179645720] 'agreement among raft nodes before linearized reading' (duration: 176.30391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:42.123963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.975217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T02:06:42.124056Z","caller":"traceutil/trace.go:171","msg":"trace[706421315] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:793; }","duration":"146.112699ms","start":"2026-04-09T02:06:41.977922Z","end":"2026-04-09T02:06:42.124035Z","steps":["trace[706421315] 'agreement among raft nodes before linearized reading' (duration: 145.838753ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:44.58919Z","caller":"traceutil/trace.go:171","msg":"trace[304771949] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"455.641642ms","start":"2026-04-09T02:06:44.133518Z","end":"2026-04-09T02:06:44.58916Z","steps":["trace[304771949] 'process raft request' (duration: 455.466439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:44.589405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.133501Z","time spent":"455.791705ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:45.170816Z","caller":"traceutil/trace.go:171","msg":"trace[1511959106] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"1.031988247s","start":"2026-04-09T02:06:44.138798Z","end":"2026-04-09T02:06:45.170786Z","steps":["trace[1511959106] 'read index received' (duration: 450.244736ms)","trace[1511959106] 'applied index is now lower than readState.Index' (duration: 581.741971ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:45.17087Z","caller":"traceutil/trace.go:171","msg":"trace[939691201] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.036584196s","start":"2026-04-09T02:06:44.134245Z","end":"2026-04-09T02:06:45.17083Z","steps":["trace[939691201] 'process raft request' (duration: 1.030561166s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:45.171021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.294006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:06:45.171064Z","caller":"traceutil/trace.go:171","msg":"trace[1109154230] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:807; }","duration":"931.381108ms","start":"2026-04-09T02:06:44.23967Z","end":"2026-04-09T02:06:45.171051Z","steps":["trace[1109154230] 'agreement among raft nodes before linearized reading' (duration: 931.266855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:45.171063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032251142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T02:06:45.171081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.134227Z","time spent":"1.03673594s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:45.171102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.239658Z","time spent":"931.433869ms","remote":"127.0.0.1:33664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:06:45.171051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.243613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:45.171187Z","caller":"traceutil/trace.go:171","msg":"trace[167390326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"377.454617ms","start":"2026-04-09T02:06:44.793707Z","end":"2026-04-09T02:06:45.171161Z","steps":["trace[167390326] 'agreement among raft nodes before linearized reading' (duration: 377.254643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:45.171238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.793679Z","time spent":"377.548029ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T02:06:45.17122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.384327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:45.171129Z","caller":"traceutil/trace.go:171","msg":"trace[69445291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"1.032374056s","start":"2026-04-09T02:06:44.138739Z","end":"2026-04-09T02:06:45.171113Z","steps":["trace[69445291] 'agreement among raft nodes before linearized reading' (duration: 1.032156291s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:45.171302Z","caller":"traceutil/trace.go:171","msg":"trace[625128721] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:807; }","duration":"615.52373ms","start":"2026-04-09T02:06:44.555761Z","end":"2026-04-09T02:06:45.171285Z","steps":["trace[625128721] 'agreement among raft nodes before linearized reading' (duration: 615.386677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:45.1714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.138715Z","time spent":"1.032638322s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:45.171482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.047832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v4qpv\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-09T02:06:45.171506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.555739Z","time spent":"615.751084ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:06:45.171628Z","caller":"traceutil/trace.go:171","msg":"trace[574164908] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v4qpv; range_end:; response_count:1; response_revision:807; }","duration":"790.246175ms","start":"2026-04-09T02:06:44.381304Z","end":"2026-04-09T02:06:45.17155Z","steps":["trace[574164908] 'agreement among raft nodes before linearized reading' (duration: 789.890398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:45.171694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:44.381285Z","time spent":"790.394159ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v4qpv\" "} {"level":"info","ts":"2026-04-09T02:06:48.894803Z","caller":"traceutil/trace.go:171","msg":"trace[40248178] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"217.887886ms","start":"2026-04-09T02:06:48.676875Z","end":"2026-04-09T02:06:48.894763Z","steps":["trace[40248178] 'process raft request' (duration: 217.63693ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:48.89485Z","caller":"traceutil/trace.go:171","msg":"trace[355071581] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"217.978038ms","start":"2026-04-09T02:06:48.676831Z","end":"2026-04-09T02:06:48.894809Z","steps":["trace[355071581] 'process raft request' (duration: 217.850965ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:49.59675Z","caller":"traceutil/trace.go:171","msg":"trace[202210080] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"151.697218ms","start":"2026-04-09T02:06:49.445029Z","end":"2026-04-09T02:06:49.596726Z","steps":["trace[202210080] 'process raft request' (duration: 151.551265ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:49.684412Z","caller":"traceutil/trace.go:171","msg":"trace[841901343] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"176.833503ms","start":"2026-04-09T02:06:49.507448Z","end":"2026-04-09T02:06:49.684281Z","steps":["trace[841901343] 'process raft request' (duration: 173.778635ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:51.511766Z","caller":"traceutil/trace.go:171","msg":"trace[113302920] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"278.61372ms","start":"2026-04-09T02:06:51.233122Z","end":"2026-04-09T02:06:51.511736Z","steps":["trace[113302920] 'process raft request' (duration: 219.058232ms)","trace[113302920] 'compare' (duration: 59.417245ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:51.869676Z","caller":"traceutil/trace.go:171","msg":"trace[642620956] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"263.140913ms","start":"2026-04-09T02:06:51.606502Z","end":"2026-04-09T02:06:51.869643Z","steps":["trace[642620956] 'process raft request' (duration: 213.494407ms)","trace[642620956] 'compare' (duration: 49.472902ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:51.875774Z","caller":"traceutil/trace.go:171","msg":"trace[759758141] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"166.834639ms","start":"2026-04-09T02:06:51.708898Z","end":"2026-04-09T02:06:51.875732Z","steps":["trace[759758141] 'process raft request' (duration: 166.525172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.296583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.58202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:52.296708Z","caller":"traceutil/trace.go:171","msg":"trace[327154835] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"317.746231ms","start":"2026-04-09T02:06:51.978946Z","end":"2026-04-09T02:06:52.296692Z","steps":["trace[327154835] 'read index received' (duration: 135.822543ms)","trace[327154835] 'applied index is now lower than readState.Index' (duration: 181.922568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:52.29685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.907014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T02:06:52.296838Z","caller":"traceutil/trace.go:171","msg":"trace[373884641] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"336.804359ms","start":"2026-04-09T02:06:51.959982Z","end":"2026-04-09T02:06:52.296787Z","steps":["trace[373884641] 'process raft request' (duration: 154.913582ms)","trace[373884641] 'compare' (duration: 181.438026ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:52.296887Z","caller":"traceutil/trace.go:171","msg":"trace[1061703524] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:875; }","duration":"317.986086ms","start":"2026-04-09T02:06:51.97889Z","end":"2026-04-09T02:06:52.296877Z","steps":["trace[1061703524] 'agreement among raft nodes before linearized reading' (duration: 317.847503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.296933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.978866Z","time spent":"318.056277ms","remote":"127.0.0.1:33350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-04-09T02:06:52.29703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.959957Z","time spent":"336.971652ms","remote":"127.0.0.1:33580","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-04-09T02:06:56.909697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.569952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:56.90978Z","caller":"traceutil/trace.go:171","msg":"trace[625056404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"117.693135ms","start":"2026-04-09T02:06:56.792068Z","end":"2026-04-09T02:06:56.909761Z","steps":["trace[625056404] 'range keys from in-memory index tree' (duration: 117.50275ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:00.676375Z","caller":"traceutil/trace.go:171","msg":"trace[1467581491] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"140.026046ms","start":"2026-04-09T02:07:00.536316Z","end":"2026-04-09T02:07:00.676342Z","steps":["trace[1467581491] 'process raft request' (duration: 139.802802ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:00.682089Z","caller":"traceutil/trace.go:171","msg":"trace[839616391] transaction","detail":"{read_only:false; number_of_response:1; response_revision:984; }","duration":"105.148553ms","start":"2026-04-09T02:07:00.576906Z","end":"2026-04-09T02:07:00.682054Z","steps":["trace[839616391] 'process raft request' (duration: 104.791865ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:02.956666Z","caller":"traceutil/trace.go:171","msg":"trace[1992080391] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"112.336253ms","start":"2026-04-09T02:07:02.844284Z","end":"2026-04-09T02:07:02.95662Z","steps":["trace[1992080391] 'process raft request' (duration: 91.526715ms)","trace[1992080391] 'compare' (duration: 20.540592ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:02.956815Z","caller":"traceutil/trace.go:171","msg":"trace[2100616571] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"111.010045ms","start":"2026-04-09T02:07:02.845762Z","end":"2026-04-09T02:07:02.956773Z","steps":["trace[2100616571] 'process raft request' (duration: 110.80369ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:02.956929Z","caller":"traceutil/trace.go:171","msg":"trace[1958667978] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"110.994154ms","start":"2026-04-09T02:07:02.845879Z","end":"2026-04-09T02:07:02.956873Z","steps":["trace[1958667978] 'process raft request' (duration: 110.769639ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:02.957003Z","caller":"traceutil/trace.go:171","msg":"trace[1764180899] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"110.053042ms","start":"2026-04-09T02:07:02.846923Z","end":"2026-04-09T02:07:02.956976Z","steps":["trace[1764180899] 'process raft request' (duration: 109.93412ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:02.957057Z","caller":"traceutil/trace.go:171","msg":"trace[287704139] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"110.249557ms","start":"2026-04-09T02:07:02.846789Z","end":"2026-04-09T02:07:02.957038Z","steps":["trace[287704139] 'process raft request' (duration: 110.011661ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:02.956988Z","caller":"traceutil/trace.go:171","msg":"trace[1146815394] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"110.286847ms","start":"2026-04-09T02:07:02.84668Z","end":"2026-04-09T02:07:02.956966Z","steps":["trace[1146815394] 'process raft request' (duration: 110.047202ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:09.64957Z","caller":"traceutil/trace.go:171","msg":"trace[1554479888] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"140.566998ms","start":"2026-04-09T02:07:09.50896Z","end":"2026-04-09T02:07:09.649527Z","steps":["trace[1554479888] 'read index received' (duration: 140.000286ms)","trace[1554479888] 'applied index is now lower than readState.Index' (duration: 564.512µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:09.649947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.964508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-d4w6d\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-04-09T02:07:09.650024Z","caller":"traceutil/trace.go:171","msg":"trace[499192762] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-548949fc64-d4w6d; range_end:; response_count:1; response_revision:1164; }","duration":"141.09529ms","start":"2026-04-09T02:07:09.508909Z","end":"2026-04-09T02:07:09.650004Z","steps":["trace[499192762] 'agreement among raft nodes before linearized reading' (duration: 140.818714ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:09.649707Z","caller":"traceutil/trace.go:171","msg":"trace[973683963] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"245.767303ms","start":"2026-04-09T02:07:09.403889Z","end":"2026-04-09T02:07:09.649656Z","steps":["trace[973683963] 'process raft request' (duration: 245.18018ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:17.107609Z","caller":"traceutil/trace.go:171","msg":"trace[468540232] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"125.110991ms","start":"2026-04-09T02:07:16.982445Z","end":"2026-04-09T02:07:17.107556Z","steps":["trace[468540232] 'process raft request' (duration: 124.927557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.563477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217270892s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-09T02:07:18.563746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.619844ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:18.563679Z","caller":"traceutil/trace.go:171","msg":"trace[1803891654] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"864.585917ms","start":"2026-04-09T02:07:17.69907Z","end":"2026-04-09T02:07:18.563656Z","steps":["trace[1803891654] 'read index received' (duration: 59.812µs)","trace[1803891654] 'applied index is now lower than readState.Index' (duration: 864.524175ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:18.563964Z","caller":"traceutil/trace.go:171","msg":"trace[1537739366] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1215; }","duration":"324.854879ms","start":"2026-04-09T02:07:18.239077Z","end":"2026-04-09T02:07:18.563932Z","steps":["trace[1537739366] 'range keys from in-memory index tree' (duration: 324.593274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.564277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.19452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-09T02:07:18.564291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.412001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-09T02:07:18.564375Z","caller":"traceutil/trace.go:171","msg":"trace[936829165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"865.359904ms","start":"2026-04-09T02:07:17.698994Z","end":"2026-04-09T02:07:18.564354Z","steps":["trace[936829165] 'agreement among raft nodes before linearized reading' (duration: 865.108248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.564372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.857772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-09T02:07:18.564398Z","caller":"traceutil/trace.go:171","msg":"trace[81982929] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1215; }","duration":"560.588966ms","start":"2026-04-09T02:07:18.003781Z","end":"2026-04-09T02:07:18.56437Z","steps":["trace[81982929] 'agreement among raft nodes before linearized reading' (duration: 560.383872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.564421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.698967Z","time spent":"865.445136ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:07:18.564324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.659245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:07:18.564458Z","caller":"traceutil/trace.go:171","msg":"trace[1574136178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"379.003316ms","start":"2026-04-09T02:07:18.185435Z","end":"2026-04-09T02:07:18.564438Z","steps":["trace[1574136178] 'agreement among raft nodes before linearized reading' (duration: 378.727549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.564461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:18.003742Z","time spent":"560.706869ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":246,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-04-09T02:07:18.564506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:18.185405Z","time spent":"379.092368ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T02:07:18.56449Z","caller":"traceutil/trace.go:171","msg":"trace[258221856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"802.847179ms","start":"2026-04-09T02:07:17.761614Z","end":"2026-04-09T02:07:18.564461Z","steps":["trace[258221856] 'agreement among raft nodes before linearized reading' (duration: 802.525542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:18.564583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:17.761598Z","time spent":"802.961141ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T02:07:19.765059Z","caller":"traceutil/trace.go:171","msg":"trace[916977783] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"168.423214ms","start":"2026-04-09T02:07:19.596614Z","end":"2026-04-09T02:07:19.765037Z","steps":["trace[916977783] 'process raft request' (duration: 168.26035ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:21.959953Z","caller":"traceutil/trace.go:171","msg":"trace[774482601] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"101.970941ms","start":"2026-04-09T02:07:21.857949Z","end":"2026-04-09T02:07:21.95992Z","steps":["trace[774482601] 'process raft request' (duration: 99.28478ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:22.192718Z","caller":"traceutil/trace.go:171","msg":"trace[703238302] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1272; }","duration":"212.142566ms","start":"2026-04-09T02:07:21.980544Z","end":"2026-04-09T02:07:22.192686Z","steps":["trace[703238302] 'read index received' (duration: 195.216555ms)","trace[703238302] 'applied index is now lower than readState.Index' (duration: 16.924671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:22.192993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.433713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T02:07:22.193031Z","caller":"traceutil/trace.go:171","msg":"trace[1716456019] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:1238; }","duration":"212.541756ms","start":"2026-04-09T02:07:21.980476Z","end":"2026-04-09T02:07:22.193018Z","steps":["trace[1716456019] 'agreement among raft nodes before linearized reading' (duration: 212.327231ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:22.193034Z","caller":"traceutil/trace.go:171","msg":"trace[2016486217] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"225.955937ms","start":"2026-04-09T02:07:21.967023Z","end":"2026-04-09T02:07:22.192979Z","steps":["trace[2016486217] 'process raft request' (duration: 208.703428ms)","trace[2016486217] 'compare' (duration: 16.628074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:22.432869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.788779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-09T02:07:22.433035Z","caller":"traceutil/trace.go:171","msg":"trace[114629278] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1242; }","duration":"144.872154ms","start":"2026-04-09T02:07:22.288139Z","end":"2026-04-09T02:07:22.433012Z","steps":["trace[114629278] 'process raft request' (duration: 33.695937ms)","trace[114629278] 'compare' (duration: 110.40608ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:22.726013Z","caller":"traceutil/trace.go:171","msg":"trace[521641372] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"148.880645ms","start":"2026-04-09T02:07:22.577083Z","end":"2026-04-09T02:07:22.725964Z","steps":["trace[521641372] 'process raft request' (duration: 144.869964ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:22.726579Z","caller":"traceutil/trace.go:171","msg":"trace[160845360] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1247; }","duration":"135.834731ms","start":"2026-04-09T02:07:22.590721Z","end":"2026-04-09T02:07:22.726556Z","steps":["trace[160845360] 'process raft request' (duration: 135.470533ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:22.726788Z","caller":"traceutil/trace.go:171","msg":"trace[1909129752] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"112.872266ms","start":"2026-04-09T02:07:22.61386Z","end":"2026-04-09T02:07:22.726732Z","steps":["trace[1909129752] 'read index received' (duration: 107.432793ms)","trace[1909129752] 'applied index is now lower than readState.Index' (duration: 5.435033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:22.727178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.298395ms","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-04-09T02:07:22.727276Z","caller":"traceutil/trace.go:171","msg":"trace[567337167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"113.438658ms","start":"2026-04-09T02:07:22.613817Z","end":"2026-04-09T02:07:22.727256Z","steps":["trace[567337167] 'agreement among raft nodes before linearized reading' (duration: 113.0816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:22.731309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.546174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:07:22.731424Z","caller":"traceutil/trace.go:171","msg":"trace[750485054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"110.731008ms","start":"2026-04-09T02:07:22.620666Z","end":"2026-04-09T02:07:22.731397Z","steps":["trace[750485054] 'agreement among raft nodes before linearized reading' (duration: 110.221527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:22.731799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.498012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:07:22.731877Z","caller":"traceutil/trace.go:171","msg":"trace[1477487257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"110.635125ms","start":"2026-04-09T02:07:22.621224Z","end":"2026-04-09T02:07:22.731859Z","steps":["trace[1477487257] 'agreement among raft nodes before linearized reading' (duration: 110.431701ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:24.973138Z","caller":"traceutil/trace.go:171","msg":"trace[2508717] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"108.038747ms","start":"2026-04-09T02:07:24.865076Z","end":"2026-04-09T02:07:24.973115Z","steps":["trace[2508717] 'process raft request' (duration: 50.179687ms)","trace[2508717] 'compare' (duration: 57.342579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:24.9734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.21171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/cert-manager-selfsigned-ca\" ","response":"range_response_count:1 size:2479"} {"level":"info","ts":"2026-04-09T02:07:24.973444Z","caller":"traceutil/trace.go:171","msg":"trace[1765858645] range","detail":"{range_begin:/registry/secrets/cert-manager/cert-manager-selfsigned-ca; range_end:; response_count:1; response_revision:1291; }","duration":"116.287922ms","start":"2026-04-09T02:07:24.857144Z","end":"2026-04-09T02:07:24.973432Z","steps":["trace[1765858645] 'agreement among raft nodes before linearized reading' (duration: 116.141559ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:30.939537Z","caller":"traceutil/trace.go:171","msg":"trace[1156247238] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"113.028021ms","start":"2026-04-09T02:07:30.826198Z","end":"2026-04-09T02:07:30.939226Z","steps":["trace[1156247238] 'process raft request' (duration: 112.782445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:54.530103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.506466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:1 size:130551"} {"level":"info","ts":"2026-04-09T02:07:54.530227Z","caller":"traceutil/trace.go:171","msg":"trace[1277735803] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:1; response_revision:1670; }","duration":"241.682829ms","start":"2026-04-09T02:07:54.288518Z","end":"2026-04-09T02:07:54.530201Z","steps":["trace[1277735803] 'range keys from in-memory index tree' (duration: 241.323382ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:54.530613Z","caller":"traceutil/trace.go:171","msg":"trace[1797234046] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"235.912409ms","start":"2026-04-09T02:07:54.294647Z","end":"2026-04-09T02:07:54.530559Z","steps":["trace[1797234046] 'process raft request' (duration: 138.907489ms)","trace[1797234046] 'compare' (duration: 96.276914ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:54.683402Z","caller":"traceutil/trace.go:171","msg":"trace[484361994] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"136.343632ms","start":"2026-04-09T02:07:54.54699Z","end":"2026-04-09T02:07:54.683334Z","steps":["trace[484361994] 'process raft request' (duration: 83.655107ms)","trace[484361994] 'compare' (duration: 52.430199ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:03.325411Z","caller":"traceutil/trace.go:171","msg":"trace[268446330] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1856; }","duration":"165.339138ms","start":"2026-04-09T02:08:03.160042Z","end":"2026-04-09T02:08:03.325381Z","steps":["trace[268446330] 'read index received' (duration: 165.223016ms)","trace[268446330] 'applied index is now lower than readState.Index' (duration: 114.632µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:03.325566Z","caller":"traceutil/trace.go:171","msg":"trace[51213102] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"259.531324ms","start":"2026-04-09T02:08:03.065985Z","end":"2026-04-09T02:08:03.325517Z","steps":["trace[51213102] 'process raft request' (duration: 259.184266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:03.325794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.737827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-09T02:08:03.325849Z","caller":"traceutil/trace.go:171","msg":"trace[1037596474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1789; }","duration":"165.818969ms","start":"2026-04-09T02:08:03.160015Z","end":"2026-04-09T02:08:03.325834Z","steps":["trace[1037596474] 'agreement among raft nodes before linearized reading' (duration: 165.524813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:03.37429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.121602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T02:08:03.374341Z","caller":"traceutil/trace.go:171","msg":"trace[1557833936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1790; }","duration":"213.190354ms","start":"2026-04-09T02:08:03.161136Z","end":"2026-04-09T02:08:03.374326Z","steps":["trace[1557833936] 'agreement among raft nodes before linearized reading' (duration: 213.04715ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:03.374325Z","caller":"traceutil/trace.go:171","msg":"trace[1481544585] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"135.758037ms","start":"2026-04-09T02:08:03.238501Z","end":"2026-04-09T02:08:03.374259Z","steps":["trace[1481544585] 'process raft request' (duration: 135.607804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:03.374423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.774264ms","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-04-09T02:08:03.374506Z","caller":"traceutil/trace.go:171","msg":"trace[1678581003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1790; }","duration":"212.883417ms","start":"2026-04-09T02:08:03.161603Z","end":"2026-04-09T02:08:03.374486Z","steps":["trace[1678581003] 'agreement among raft nodes before linearized reading' (duration: 212.636302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:03.37466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.292546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-09T02:08:03.374774Z","caller":"traceutil/trace.go:171","msg":"trace[1045075353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1790; }","duration":"213.46645ms","start":"2026-04-09T02:08:03.161279Z","end":"2026-04-09T02:08:03.374745Z","steps":["trace[1045075353] 'agreement among raft nodes before linearized reading' (duration: 213.106292ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:10.610576Z","caller":"traceutil/trace.go:171","msg":"trace[385178967] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"361.331133ms","start":"2026-04-09T02:08:10.249208Z","end":"2026-04-09T02:08:10.610539Z","steps":["trace[385178967] 'process raft request' (duration: 361.066817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:10.610781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:10.249177Z","time spent":"361.497376ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:08:10.791019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.857257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T02:08:10.791144Z","caller":"traceutil/trace.go:171","msg":"trace[1205058294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"101.014741ms","start":"2026-04-09T02:08:10.690097Z","end":"2026-04-09T02:08:10.791112Z","steps":["trace[1205058294] 'range keys from in-memory index tree' (duration: 100.712763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:10.791019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.619006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-09T02:08:10.791275Z","caller":"traceutil/trace.go:171","msg":"trace[1364674139] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1897; }","duration":"135.942033ms","start":"2026-04-09T02:08:10.655315Z","end":"2026-04-09T02:08:10.791257Z","steps":["trace[1364674139] 'range keys from in-memory index tree' (duration: 135.410671ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:11.37984Z","caller":"traceutil/trace.go:171","msg":"trace[1414077379] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"492.743922ms","start":"2026-04-09T02:08:10.887056Z","end":"2026-04-09T02:08:11.3798Z","steps":["trace[1414077379] 'process raft request' (duration: 492.467406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:11.380209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:10.887019Z","time spent":"492.994298ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:08:15.767151Z","caller":"traceutil/trace.go:171","msg":"trace[581185688] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2016; }","duration":"139.963573ms","start":"2026-04-09T02:08:15.627158Z","end":"2026-04-09T02:08:15.767121Z","steps":["trace[581185688] 'read index received' (duration: 139.743058ms)","trace[581185688] 'applied index is now lower than readState.Index' (duration: 219.095µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:15.767249Z","caller":"traceutil/trace.go:171","msg":"trace[1117253824] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"140.185728ms","start":"2026-04-09T02:08:15.627026Z","end":"2026-04-09T02:08:15.767212Z","steps":["trace[1117253824] 'process raft request' (duration: 139.870701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:15.767478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.29224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T02:08:15.767528Z","caller":"traceutil/trace.go:171","msg":"trace[844078461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1947; }","duration":"140.420904ms","start":"2026-04-09T02:08:15.627094Z","end":"2026-04-09T02:08:15.767515Z","steps":["trace[844078461] 'agreement among raft nodes before linearized reading' (duration: 140.217759ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:21.096764Z","caller":"traceutil/trace.go:171","msg":"trace[1067161137] linearizableReadLoop","detail":"{readStateIndex:2062; appliedIndex:2061; }","duration":"112.837608ms","start":"2026-04-09T02:08:20.983894Z","end":"2026-04-09T02:08:21.096732Z","steps":["trace[1067161137] 'read index received' (duration: 87.254868ms)","trace[1067161137] 'applied index is now lower than readState.Index' (duration: 25.58128ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:21.096878Z","caller":"traceutil/trace.go:171","msg":"trace[1686856649] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"127.287345ms","start":"2026-04-09T02:08:20.969542Z","end":"2026-04-09T02:08:21.096829Z","steps":["trace[1686856649] 'process raft request' (duration: 101.577233ms)","trace[1686856649] 'compare' (duration: 25.290053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:08:21.097056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.151855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-09T02:08:21.097151Z","caller":"traceutil/trace.go:171","msg":"trace[1031392784] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1991; }","duration":"113.294029ms","start":"2026-04-09T02:08:20.98383Z","end":"2026-04-09T02:08:21.097124Z","steps":["trace[1031392784] 'agreement among raft nodes before linearized reading' (duration: 113.029372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:26.569228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.063136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:08:26.569319Z","caller":"traceutil/trace.go:171","msg":"trace[46934039] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2034; }","duration":"432.24611ms","start":"2026-04-09T02:08:26.137056Z","end":"2026-04-09T02:08:26.569302Z","steps":["trace[46934039] 'count revisions from in-memory index tree' (duration: 431.938493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:26.569362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:26.137026Z","time spent":"432.327752ms","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:08:56.362739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.381064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:08:56.362832Z","caller":"traceutil/trace.go:171","msg":"trace[2126424841] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2203; }","duration":"114.523397ms","start":"2026-04-09T02:08:56.24829Z","end":"2026-04-09T02:08:56.362813Z","steps":["trace[2126424841] 'count revisions from in-memory index tree' (duration: 114.306532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:57.476798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.379834ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T02:09:03.20123Z","caller":"traceutil/trace.go:171","msg":"trace[755622297] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"101.252525ms","start":"2026-04-09T02:09:03.099941Z","end":"2026-04-09T02:09:03.201194Z","steps":["trace[755622297] 'process raft request' (duration: 101.03672ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:03.71608Z","caller":"traceutil/trace.go:171","msg":"trace[1626868793] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"105.086678ms","start":"2026-04-09T02:09:03.610959Z","end":"2026-04-09T02:09:03.716046Z","steps":["trace[1626868793] 'process raft request' (duration: 104.827433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.496527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676849221,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:09:58.615461Z","caller":"traceutil/trace.go:171","msg":"trace[1426373447] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"627.033745ms","start":"2026-04-09T02:09:57.988399Z","end":"2026-04-09T02:09:58.615432Z","steps":["trace[1426373447] 'process raft request' (duration: 626.880282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.615679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:57.988366Z","time spent":"627.180469ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:09:58.659133Z","caller":"traceutil/trace.go:171","msg":"trace[960352820] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2802; }","duration":"663.36408ms","start":"2026-04-09T02:09:57.995735Z","end":"2026-04-09T02:09:58.659099Z","steps":["trace[960352820] 'read index received' (duration: 619.676277ms)","trace[960352820] 'applied index is now lower than readState.Index' (duration: 43.685953ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:09:58.659162Z","caller":"traceutil/trace.go:171","msg":"trace[1300902488] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"328.044212ms","start":"2026-04-09T02:09:58.331086Z","end":"2026-04-09T02:09:58.659131Z","steps":["trace[1300902488] 'process raft request' (duration: 327.771506ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:58.65924Z","caller":"traceutil/trace.go:171","msg":"trace[419273206] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"263.403756ms","start":"2026-04-09T02:09:58.39581Z","end":"2026-04-09T02:09:58.659213Z","steps":["trace[419273206] 'process raft request' (duration: 263.233082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.659369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.628086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:09:58.659417Z","caller":"traceutil/trace.go:171","msg":"trace[241462692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2704; }","duration":"663.687638ms","start":"2026-04-09T02:09:57.995718Z","end":"2026-04-09T02:09:58.659406Z","steps":["trace[241462692] 'agreement among raft nodes before linearized reading' (duration: 663.503244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.659447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.720144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T02:09:58.659458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:57.995656Z","time spent":"663.79381ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T02:09:58.65949Z","caller":"traceutil/trace.go:171","msg":"trace[363270502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2704; }","duration":"176.810046ms","start":"2026-04-09T02:09:58.48267Z","end":"2026-04-09T02:09:58.65948Z","steps":["trace[363270502] 'agreement among raft nodes before linearized reading' (duration: 176.701074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.659636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.566703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:09:58.659664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.586242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T02:09:58.659678Z","caller":"traceutil/trace.go:171","msg":"trace[1101435804] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2704; }","duration":"650.612354ms","start":"2026-04-09T02:09:58.009053Z","end":"2026-04-09T02:09:58.659666Z","steps":["trace[1101435804] 'agreement among raft nodes before linearized reading' (duration: 650.539973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.659657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:58.331061Z","time spent":"328.225795ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:09:58.65966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.972163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-09T02:09:58.65963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.869461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T02:09:58.659712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:58.00902Z","time spent":"650.682376ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:09:58.659731Z","caller":"traceutil/trace.go:171","msg":"trace[899981057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2704; }","duration":"576.068124ms","start":"2026-04-09T02:09:58.083649Z","end":"2026-04-09T02:09:58.659718Z","steps":["trace[899981057] 'agreement among raft nodes before linearized reading' (duration: 575.86409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.659772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:58.083633Z","time spent":"576.131376ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T02:09:58.659714Z","caller":"traceutil/trace.go:171","msg":"trace[933561583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2704; }","duration":"579.636253ms","start":"2026-04-09T02:09:58.080062Z","end":"2026-04-09T02:09:58.659698Z","steps":["trace[933561583] 'agreement among raft nodes before linearized reading' (duration: 579.432579ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:58.659768Z","caller":"traceutil/trace.go:171","msg":"trace[28923463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2704; }","duration":"652.113217ms","start":"2026-04-09T02:09:58.00763Z","end":"2026-04-09T02:09:58.659743Z","steps":["trace[28923463] 'agreement among raft nodes before linearized reading' (duration: 651.745389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:58.65983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:58.079998Z","time spent":"579.820656ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:09:58.659878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:58.007604Z","time spent":"652.26071ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:09:58.660201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.912638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-09T02:09:58.660614Z","caller":"traceutil/trace.go:171","msg":"trace[1618856760] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2704; }","duration":"126.245397ms","start":"2026-04-09T02:09:58.534238Z","end":"2026-04-09T02:09:58.660484Z","steps":["trace[1618856760] 'agreement among raft nodes before linearized reading' (duration: 125.346526ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:04.856174Z","caller":"traceutil/trace.go:171","msg":"trace[2006826341] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"179.000201ms","start":"2026-04-09T02:10:04.677147Z","end":"2026-04-09T02:10:04.856147Z","steps":["trace[2006826341] 'process raft request' (duration: 174.03442ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:04.856559Z","caller":"traceutil/trace.go:171","msg":"trace[550947979] linearizableReadLoop","detail":"{readStateIndex:2845; appliedIndex:2843; }","duration":"164.771492ms","start":"2026-04-09T02:10:04.691758Z","end":"2026-04-09T02:10:04.85653Z","steps":["trace[550947979] 'read index received' (duration: 159.441552ms)","trace[550947979] 'applied index is now lower than readState.Index' (duration: 5.32877ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:10:04.856613Z","caller":"traceutil/trace.go:171","msg":"trace[145303301] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"178.235974ms","start":"2026-04-09T02:10:04.678341Z","end":"2026-04-09T02:10:04.856577Z","steps":["trace[145303301] 'process raft request' (duration: 178.07139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:04.856761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.80365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:10:04.856809Z","caller":"traceutil/trace.go:171","msg":"trace[853707234] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2744; }","duration":"113.922633ms","start":"2026-04-09T02:10:04.742872Z","end":"2026-04-09T02:10:04.856794Z","steps":["trace[853707234] 'agreement among raft nodes before linearized reading' (duration: 113.834901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:04.856825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.055458ms","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-04-09T02:10:04.856879Z","caller":"traceutil/trace.go:171","msg":"trace[2013794394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2744; }","duration":"165.123809ms","start":"2026-04-09T02:10:04.691741Z","end":"2026-04-09T02:10:04.856865Z","steps":["trace[2013794394] 'agreement among raft nodes before linearized reading' (duration: 164.921495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:04.8569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.56781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T02:10:04.856956Z","caller":"traceutil/trace.go:171","msg":"trace[1998283019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2744; }","duration":"157.688724ms","start":"2026-04-09T02:10:04.699252Z","end":"2026-04-09T02:10:04.856941Z","steps":["trace[1998283019] 'agreement among raft nodes before linearized reading' (duration: 157.548181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:04.856963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.98954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T02:10:04.856998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.118671ms","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-04-09T02:10:04.857062Z","caller":"traceutil/trace.go:171","msg":"trace[291776238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2744; }","duration":"157.217094ms","start":"2026-04-09T02:10:04.699829Z","end":"2026-04-09T02:10:04.857046Z","steps":["trace[291776238] 'agreement among raft nodes before linearized reading' (duration: 157.013269ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:04.857026Z","caller":"traceutil/trace.go:171","msg":"trace[1941897141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2744; }","duration":"158.094702ms","start":"2026-04-09T02:10:04.698917Z","end":"2026-04-09T02:10:04.857012Z","steps":["trace[1941897141] 'agreement among raft nodes before linearized reading' (duration: 157.914458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:04.857054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.248391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:10:04.857148Z","caller":"traceutil/trace.go:171","msg":"trace[722338983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2744; }","duration":"160.375165ms","start":"2026-04-09T02:10:04.696748Z","end":"2026-04-09T02:10:04.857123Z","steps":["trace[722338983] 'agreement among raft nodes before linearized reading' (duration: 159.926774ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:31.469894Z","caller":"traceutil/trace.go:171","msg":"trace[606899317] linearizableReadLoop","detail":"{readStateIndex:3005; appliedIndex:3003; }","duration":"476.757575ms","start":"2026-04-09T02:10:30.993101Z","end":"2026-04-09T02:10:31.469859Z","steps":["trace[606899317] 'read index received' (duration: 54.121414ms)","trace[606899317] 'applied index is now lower than readState.Index' (duration: 422.634691ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:10:31.470079Z","caller":"traceutil/trace.go:171","msg":"trace[446973874] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"510.139923ms","start":"2026-04-09T02:10:30.959925Z","end":"2026-04-09T02:10:31.470065Z","steps":["trace[446973874] 'process raft request' (duration: 509.685372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:31.470243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:30.959889Z","time spent":"510.230746ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:10:31.470389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.246126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T02:10:31.470479Z","caller":"traceutil/trace.go:171","msg":"trace[86369886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2899; }","duration":"477.40701ms","start":"2026-04-09T02:10:30.993052Z","end":"2026-04-09T02:10:31.47046Z","steps":["trace[86369886] 'agreement among raft nodes before linearized reading' (duration: 477.173045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:31.470524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:30.993033Z","time spent":"477.482621ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T02:10:31.491305Z","caller":"traceutil/trace.go:171","msg":"trace[1882312434] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"164.437045ms","start":"2026-04-09T02:10:31.326841Z","end":"2026-04-09T02:10:31.491278Z","steps":["trace[1882312434] 'process raft request' (duration: 164.267101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:31.491701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.221656ms","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-04-09T02:10:31.491758Z","caller":"traceutil/trace.go:171","msg":"trace[963182450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2900; }","duration":"498.306147ms","start":"2026-04-09T02:10:30.993438Z","end":"2026-04-09T02:10:31.491744Z","steps":["trace[963182450] 'agreement among raft nodes before linearized reading' (duration: 498.080092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:31.491795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:30.993429Z","time spent":"498.356189ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:10:31.491926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.424788ms","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":"warn","ts":"2026-04-09T02:10:31.491942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.724197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:10:31.492038Z","caller":"traceutil/trace.go:171","msg":"trace[355380474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2900; }","duration":"473.862419ms","start":"2026-04-09T02:10:31.018152Z","end":"2026-04-09T02:10:31.492015Z","steps":["trace[355380474] 'agreement among raft nodes before linearized reading' (duration: 473.569203ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:31.492008Z","caller":"traceutil/trace.go:171","msg":"trace[2127586621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2900; }","duration":"489.563391ms","start":"2026-04-09T02:10:31.002426Z","end":"2026-04-09T02:10:31.491989Z","steps":["trace[2127586621] 'agreement among raft nodes before linearized reading' (duration: 489.246744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:31.492086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:31.018138Z","time spent":"473.939982ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:10:31.492091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:31.002403Z","time spent":"489.675934ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T02:10:31.492157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.456894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T02:10:31.492279Z","caller":"traceutil/trace.go:171","msg":"trace[1597054420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2900; }","duration":"487.625658ms","start":"2026-04-09T02:10:31.00462Z","end":"2026-04-09T02:10:31.492245Z","steps":["trace[1597054420] 'agreement among raft nodes before linearized reading' (duration: 487.038496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:31.492337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:31.004608Z","time spent":"487.71923ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:10:32.24144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.652823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:10:32.241531Z","caller":"traceutil/trace.go:171","msg":"trace[1357490888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2905; }","duration":"447.797556ms","start":"2026-04-09T02:10:31.793713Z","end":"2026-04-09T02:10:32.241511Z","steps":["trace[1357490888] 'range keys from in-memory index tree' (duration: 447.552781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:32.241613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:31.793689Z","time spent":"447.911778ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T02:10:32.24214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.127788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T02:10:32.242178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.845009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-09T02:10:32.242182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.447102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-09T02:10:32.242238Z","caller":"traceutil/trace.go:171","msg":"trace[1694331239] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:2905; }","duration":"249.953781ms","start":"2026-04-09T02:10:31.992272Z","end":"2026-04-09T02:10:32.242226Z","steps":["trace[1694331239] 'range keys from in-memory index tree' (duration: 249.589373ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:32.24225Z","caller":"traceutil/trace.go:171","msg":"trace[1891827745] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2905; }","duration":"276.384764ms","start":"2026-04-09T02:10:31.965841Z","end":"2026-04-09T02:10:32.242225Z","steps":["trace[1891827745] 'count revisions from in-memory index tree' (duration: 275.982734ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:32.24241Z","caller":"traceutil/trace.go:171","msg":"trace[1646277043] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2905; }","duration":"566.066416ms","start":"2026-04-09T02:10:31.6762Z","end":"2026-04-09T02:10:32.242266Z","steps":["trace[1646277043] 'range keys from in-memory index tree' (duration: 565.161656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:32.242481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:31.676174Z","time spent":"566.291971ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-09T02:10:37.342395Z","caller":"traceutil/trace.go:171","msg":"trace[1330918550] transaction","detail":"{read_only:false; response_revision:2932; number_of_response:1; }","duration":"273.739685ms","start":"2026-04-09T02:10:37.068624Z","end":"2026-04-09T02:10:37.342364Z","steps":["trace[1330918550] 'process raft request' (duration: 273.552311ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:13:13.803943Z","caller":"traceutil/trace.go:171","msg":"trace[1032151091] linearizableReadLoop","detail":"{readStateIndex:4102; appliedIndex:4101; }","duration":"300.154647ms","start":"2026-04-09T02:13:13.503765Z","end":"2026-04-09T02:13:13.80392Z","steps":["trace[1032151091] 'read index received' (duration: 299.933972ms)","trace[1032151091] 'applied index is now lower than readState.Index' (duration: 219.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:13:13.804004Z","caller":"traceutil/trace.go:171","msg":"trace[1882121708] transaction","detail":"{read_only:false; response_revision:3956; number_of_response:1; }","duration":"362.094329ms","start":"2026-04-09T02:13:13.441887Z","end":"2026-04-09T02:13:13.803981Z","steps":["trace[1882121708] 'process raft request' (duration: 361.888684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:13:13.804168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:13:13.441856Z","time spent":"362.220312ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:13:13.804394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.571917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-09T02:13:13.804551Z","caller":"traceutil/trace.go:171","msg":"trace[1986536366] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3956; }","duration":"300.808062ms","start":"2026-04-09T02:13:13.503709Z","end":"2026-04-09T02:13:13.804517Z","steps":["trace[1986536366] 'agreement among raft nodes before linearized reading' (duration: 300.328391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:13:13.804632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:13:13.503687Z","time spent":"300.928455ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-04-09T02:13:14.172262Z","caller":"traceutil/trace.go:171","msg":"trace[2035644484] transaction","detail":"{read_only:false; response_revision:3957; number_of_response:1; }","duration":"188.478614ms","start":"2026-04-09T02:13:13.983762Z","end":"2026-04-09T02:13:14.172241Z","steps":["trace[2035644484] 'process raft request' (duration: 188.29378ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:13:24.62796Z","caller":"traceutil/trace.go:171","msg":"trace[1562576622] transaction","detail":"{read_only:false; response_revision:4022; number_of_response:1; }","duration":"104.487423ms","start":"2026-04-09T02:13:24.523438Z","end":"2026-04-09T02:13:24.627925Z","steps":["trace[1562576622] 'process raft request' (duration: 104.317559ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:13:49.274414Z","caller":"traceutil/trace.go:171","msg":"trace[39505683] transaction","detail":"{read_only:false; response_revision:4188; number_of_response:1; }","duration":"108.059802ms","start":"2026-04-09T02:13:49.166327Z","end":"2026-04-09T02:13:49.274387Z","steps":["trace[39505683] 'process raft request' (duration: 107.801806ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:13:49.459255Z","caller":"traceutil/trace.go:171","msg":"trace[1584135385] transaction","detail":"{read_only:false; response_revision:4190; number_of_response:1; }","duration":"110.388663ms","start":"2026-04-09T02:13:49.348839Z","end":"2026-04-09T02:13:49.459228Z","steps":["trace[1584135385] 'process raft request' (duration: 110.131038ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:13:50.302735Z","caller":"traceutil/trace.go:171","msg":"trace[1593827510] transaction","detail":"{read_only:false; response_revision:4206; number_of_response:1; }","duration":"247.318822ms","start":"2026-04-09T02:13:50.055375Z","end":"2026-04-09T02:13:50.302693Z","steps":["trace[1593827510] 'process raft request' (duration: 200.586743ms)","trace[1593827510] 'compare' (duration: 46.233638ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:13:50.304178Z","caller":"traceutil/trace.go:171","msg":"trace[1889383788] transaction","detail":"{read_only:false; response_revision:4207; number_of_response:1; }","duration":"230.289027ms","start":"2026-04-09T02:13:50.073867Z","end":"2026-04-09T02:13:50.304156Z","steps":["trace[1889383788] 'process raft request' (duration: 229.617652ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:26.632541Z","caller":"traceutil/trace.go:171","msg":"trace[1509761215] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"159.130336ms","start":"2026-04-09T02:14:26.473372Z","end":"2026-04-09T02:14:26.632502Z","steps":["trace[1509761215] 'process raft request' (duration: 158.886601ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:26.636755Z","caller":"traceutil/trace.go:171","msg":"trace[1482077355] transaction","detail":"{read_only:false; response_revision:4450; number_of_response:1; }","duration":"123.572444ms","start":"2026-04-09T02:14:26.513153Z","end":"2026-04-09T02:14:26.636725Z","steps":["trace[1482077355] 'process raft request' (duration: 123.41028ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:32.760415Z","caller":"traceutil/trace.go:171","msg":"trace[1484099986] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"232.414789ms","start":"2026-04-09T02:14:32.527932Z","end":"2026-04-09T02:14:32.760347Z","steps":["trace[1484099986] 'process raft request' (duration: 232.01106ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:32.761453Z","caller":"traceutil/trace.go:171","msg":"trace[1983060950] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"105.262837ms","start":"2026-04-09T02:14:32.656163Z","end":"2026-04-09T02:14:32.761426Z","steps":["trace[1983060950] 'process raft request' (duration: 105.088383ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:32.76154Z","caller":"traceutil/trace.go:171","msg":"trace[443550563] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"104.654563ms","start":"2026-04-09T02:14:32.656839Z","end":"2026-04-09T02:14:32.761494Z","steps":["trace[443550563] 'process raft request' (duration: 104.539641ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:36.278944Z","caller":"traceutil/trace.go:171","msg":"trace[492659214] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5111; }","duration":"113.620802ms","start":"2026-04-09T02:14:36.165298Z","end":"2026-04-09T02:14:36.278919Z","steps":["trace[492659214] 'read index received' (duration: 68.131378ms)","trace[492659214] 'applied index is now lower than readState.Index' (duration: 45.487864ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:36.278969Z","caller":"traceutil/trace.go:171","msg":"trace[136158480] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"132.477861ms","start":"2026-04-09T02:14:36.146457Z","end":"2026-04-09T02:14:36.278935Z","steps":["trace[136158480] 'process raft request' (duration: 86.878835ms)","trace[136158480] 'compare' (duration: 45.390882ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:36.279189Z","caller":"traceutil/trace.go:171","msg":"trace[1335831432] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4929; }","duration":"111.994106ms","start":"2026-04-09T02:14:36.167185Z","end":"2026-04-09T02:14:36.279179Z","steps":["trace[1335831432] 'process raft request' (duration: 111.681808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:14:36.279247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.75461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af1b9f62-0cf4-4c5a-afd2-383a96071043\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-04-09T02:14:36.279332Z","caller":"traceutil/trace.go:171","msg":"trace[113828108] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af1b9f62-0cf4-4c5a-afd2-383a96071043; range_end:; response_count:1; response_revision:4929; }","duration":"115.901064ms","start":"2026-04-09T02:14:36.163411Z","end":"2026-04-09T02:14:36.279312Z","steps":["trace[113828108] 'agreement among raft nodes before linearized reading' (duration: 115.659597ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:36.279618Z","caller":"traceutil/trace.go:171","msg":"trace[136161904] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"110.33597ms","start":"2026-04-09T02:14:36.169264Z","end":"2026-04-09T02:14:36.2796Z","steps":["trace[136161904] 'process raft request' (duration: 109.826368ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:36.279649Z","caller":"traceutil/trace.go:171","msg":"trace[389227843] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"106.816911ms","start":"2026-04-09T02:14:36.172816Z","end":"2026-04-09T02:14:36.279633Z","steps":["trace[389227843] 'process raft request' (duration: 106.553845ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:39.383945Z","caller":"traceutil/trace.go:171","msg":"trace[1146364418] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5199; }","duration":"169.800734ms","start":"2026-04-09T02:14:39.214109Z","end":"2026-04-09T02:14:39.38391Z","steps":["trace[1146364418] 'read index received' (duration: 144.377208ms)","trace[1146364418] 'applied index is now lower than readState.Index' (duration: 25.422216ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:39.384136Z","caller":"traceutil/trace.go:171","msg":"trace[2145506995] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"170.940899ms","start":"2026-04-09T02:14:39.213181Z","end":"2026-04-09T02:14:39.384122Z","steps":["trace[2145506995] 'process raft request' (duration: 145.329918ms)","trace[2145506995] 'compare' (duration: 25.218672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:14:39.384247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.118601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T02:14:39.384321Z","caller":"traceutil/trace.go:171","msg":"trace[69575152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5011; }","duration":"170.240114ms","start":"2026-04-09T02:14:39.214065Z","end":"2026-04-09T02:14:39.384306Z","steps":["trace[69575152] 'agreement among raft nodes before linearized reading' (duration: 170.023799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:14:39.384357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.348154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T02:14:39.384443Z","caller":"traceutil/trace.go:171","msg":"trace[1883319780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5011; }","duration":"169.471307ms","start":"2026-04-09T02:14:39.214952Z","end":"2026-04-09T02:14:39.384424Z","steps":["trace[1883319780] 'agreement among raft nodes before linearized reading' (duration: 169.16489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:14:39.384427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.236611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-09T02:14:39.384522Z","caller":"traceutil/trace.go:171","msg":"trace[1867511678] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5011; }","duration":"169.360113ms","start":"2026-04-09T02:14:39.215138Z","end":"2026-04-09T02:14:39.384498Z","steps":["trace[1867511678] 'agreement among raft nodes before linearized reading' (duration: 169.067397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:14:39.384721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.5415ms","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-04-09T02:14:39.384796Z","caller":"traceutil/trace.go:171","msg":"trace[1180020397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5011; }","duration":"135.629983ms","start":"2026-04-09T02:14:39.249151Z","end":"2026-04-09T02:14:39.384781Z","steps":["trace[1180020397] 'agreement among raft nodes before linearized reading' (duration: 135.026799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:14:39.38502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.877376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-04-09T02:14:39.385097Z","caller":"traceutil/trace.go:171","msg":"trace[1805080160] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5011; }","duration":"152.981328ms","start":"2026-04-09T02:14:39.232098Z","end":"2026-04-09T02:14:39.38508Z","steps":["trace[1805080160] 'agreement among raft nodes before linearized reading' (duration: 152.048248ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:39.538075Z","caller":"traceutil/trace.go:171","msg":"trace[111606058] linearizableReadLoop","detail":"{readStateIndex:5201; appliedIndex:5200; }","duration":"150.249018ms","start":"2026-04-09T02:14:39.387799Z","end":"2026-04-09T02:14:39.538048Z","steps":["trace[111606058] 'read index received' (duration: 102.652827ms)","trace[111606058] 'applied index is now lower than readState.Index' (duration: 47.594601ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:39.53812Z","caller":"traceutil/trace.go:171","msg":"trace[1011439555] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"150.392592ms","start":"2026-04-09T02:14:39.387689Z","end":"2026-04-09T02:14:39.538082Z","steps":["trace[1011439555] 'process raft request' (duration: 102.74958ms)","trace[1011439555] 'compare' (duration: 47.387196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:14:39.538291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.466223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:14:39.538308Z","caller":"traceutil/trace.go:171","msg":"trace[413155624] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"150.32694ms","start":"2026-04-09T02:14:39.387958Z","end":"2026-04-09T02:14:39.538284Z","steps":["trace[413155624] 'process raft request' (duration: 150.047084ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:39.538339Z","caller":"traceutil/trace.go:171","msg":"trace[985485086] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5013; }","duration":"150.532484ms","start":"2026-04-09T02:14:39.387793Z","end":"2026-04-09T02:14:39.538325Z","steps":["trace[985485086] 'agreement among raft nodes before linearized reading' (duration: 150.36351ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:39.538467Z","caller":"traceutil/trace.go:171","msg":"trace[793922907] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"149.682215ms","start":"2026-04-09T02:14:39.388773Z","end":"2026-04-09T02:14:39.538456Z","steps":["trace[793922907] 'process raft request' (duration: 149.44416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:14:39.538739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.864264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-09T02:14:39.538796Z","caller":"traceutil/trace.go:171","msg":"trace[341799120] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5015; }","duration":"120.977856ms","start":"2026-04-09T02:14:39.417806Z","end":"2026-04-09T02:14:39.538784Z","steps":["trace[341799120] 'agreement among raft nodes before linearized reading' (duration: 120.766001ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:39.782422Z","caller":"traceutil/trace.go:171","msg":"trace[394367659] linearizableReadLoop","detail":"{readStateIndex:5205; appliedIndex:5204; }","duration":"156.076338ms","start":"2026-04-09T02:14:39.626326Z","end":"2026-04-09T02:14:39.782403Z","steps":["trace[394367659] 'read index received' (duration: 155.913495ms)","trace[394367659] 'applied index is now lower than readState.Index' (duration: 161.943µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:14:39.782609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.258332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:14:39.782669Z","caller":"traceutil/trace.go:171","msg":"trace[1085595867] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"168.906534ms","start":"2026-04-09T02:14:39.613748Z","end":"2026-04-09T02:14:39.782655Z","steps":["trace[1085595867] 'process raft request' (duration: 168.495795ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:39.782888Z","caller":"traceutil/trace.go:171","msg":"trace[550898440] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5016; }","duration":"156.391915ms","start":"2026-04-09T02:14:39.626281Z","end":"2026-04-09T02:14:39.782673Z","steps":["trace[550898440] 'agreement among raft nodes before linearized reading' (duration: 156.238552ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:40.911336Z","caller":"traceutil/trace.go:171","msg":"trace[1406666702] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5027; }","duration":"193.039201ms","start":"2026-04-09T02:14:40.718238Z","end":"2026-04-09T02:14:40.911277Z","steps":["trace[1406666702] 'process raft request' (duration: 192.903718ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:44.44223Z","caller":"traceutil/trace.go:171","msg":"trace[1792065292] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"123.814158ms","start":"2026-04-09T02:14:44.318393Z","end":"2026-04-09T02:14:44.442207Z","steps":["trace[1792065292] 'process raft request' (duration: 123.252406ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:46.566624Z","caller":"traceutil/trace.go:171","msg":"trace[72614130] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"117.126681ms","start":"2026-04-09T02:14:46.449458Z","end":"2026-04-09T02:14:46.566584Z","steps":["trace[72614130] 'process raft request' (duration: 116.841024ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.262879Z","caller":"traceutil/trace.go:171","msg":"trace[323235452] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"125.488807ms","start":"2026-04-09T02:14:54.13737Z","end":"2026-04-09T02:14:54.262859Z","steps":["trace[323235452] 'process raft request' (duration: 95.648051ms)","trace[323235452] 'compare' (duration: 29.701553ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:54.491648Z","caller":"traceutil/trace.go:171","msg":"trace[1303438136] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"159.523755ms","start":"2026-04-09T02:14:54.332097Z","end":"2026-04-09T02:14:54.491621Z","steps":["trace[1303438136] 'process raft request' (duration: 159.383962ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.491676Z","caller":"traceutil/trace.go:171","msg":"trace[851572637] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"164.027805ms","start":"2026-04-09T02:14:54.327601Z","end":"2026-04-09T02:14:54.491629Z","steps":["trace[851572637] 'process raft request' (duration: 124.534665ms)","trace[851572637] 'compare' (duration: 39.151202ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:54.491779Z","caller":"traceutil/trace.go:171","msg":"trace[1530589328] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"159.553405ms","start":"2026-04-09T02:14:54.332209Z","end":"2026-04-09T02:14:54.491762Z","steps":["trace[1530589328] 'process raft request' (duration: 159.469353ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.491917Z","caller":"traceutil/trace.go:171","msg":"trace[1736807508] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"159.78796ms","start":"2026-04-09T02:14:54.332119Z","end":"2026-04-09T02:14:54.491907Z","steps":["trace[1736807508] 'process raft request' (duration: 159.423842ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.693989Z","caller":"traceutil/trace.go:171","msg":"trace[586465463] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"197.229135ms","start":"2026-04-09T02:14:54.496735Z","end":"2026-04-09T02:14:54.693964Z","steps":["trace[586465463] 'process raft request' (duration: 162.620544ms)","trace[586465463] 'compare' (duration: 34.500738ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:54.764488Z","caller":"traceutil/trace.go:171","msg":"trace[1705886630] linearizableReadLoop","detail":"{readStateIndex:5580; appliedIndex:5578; }","duration":"264.346171ms","start":"2026-04-09T02:14:54.500124Z","end":"2026-04-09T02:14:54.764471Z","steps":["trace[1705886630] 'read index received' (duration: 159.254289ms)","trace[1705886630] 'applied index is now lower than readState.Index' (duration: 105.091252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:14:54.764652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.515804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jfq9p\" ","response":"range_response_count:1 size:9538"} {"level":"info","ts":"2026-04-09T02:14:54.764678Z","caller":"traceutil/trace.go:171","msg":"trace[117458050] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jfq9p; range_end:; response_count:1; response_revision:5383; }","duration":"264.585086ms","start":"2026-04-09T02:14:54.500081Z","end":"2026-04-09T02:14:54.764666Z","steps":["trace[117458050] 'agreement among raft nodes before linearized reading' (duration: 264.459583ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.764663Z","caller":"traceutil/trace.go:171","msg":"trace[1917627673] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"158.637544ms","start":"2026-04-09T02:14:54.605978Z","end":"2026-04-09T02:14:54.764615Z","steps":["trace[1917627673] 'process raft request' (duration: 158.45471ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.764922Z","caller":"traceutil/trace.go:171","msg":"trace[562790861] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"266.404327ms","start":"2026-04-09T02:14:54.498469Z","end":"2026-04-09T02:14:54.764874Z","steps":["trace[562790861] 'process raft request' (duration: 265.828894ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:54.765057Z","caller":"traceutil/trace.go:171","msg":"trace[861960220] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"262.702784ms","start":"2026-04-09T02:14:54.502303Z","end":"2026-04-09T02:14:54.765006Z","steps":["trace[861960220] 'process raft request' (duration: 262.09906ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:01.061346Z","caller":"traceutil/trace.go:171","msg":"trace[1484300217] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"184.110863ms","start":"2026-04-09T02:15:00.877196Z","end":"2026-04-09T02:15:01.061307Z","steps":["trace[1484300217] 'process raft request' (duration: 183.434528ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:01.071241Z","caller":"traceutil/trace.go:171","msg":"trace[920903238] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"187.957778ms","start":"2026-04-09T02:15:00.883259Z","end":"2026-04-09T02:15:01.071217Z","steps":["trace[920903238] 'process raft request' (duration: 187.853126ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:01.0712Z","caller":"traceutil/trace.go:171","msg":"trace[688357555] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"188.859839ms","start":"2026-04-09T02:15:00.882285Z","end":"2026-04-09T02:15:01.071145Z","steps":["trace[688357555] 'process raft request' (duration: 188.672975ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:01.071274Z","caller":"traceutil/trace.go:171","msg":"trace[1765735785] linearizableReadLoop","detail":"{readStateIndex:5699; appliedIndex:5695; }","duration":"187.277814ms","start":"2026-04-09T02:15:00.883975Z","end":"2026-04-09T02:15:01.071253Z","steps":["trace[1765735785] 'read index received' (duration: 176.674818ms)","trace[1765735785] 'applied index is now lower than readState.Index' (duration: 10.602176ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:01.071238Z","caller":"traceutil/trace.go:171","msg":"trace[641615525] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"188.741726ms","start":"2026-04-09T02:15:00.882462Z","end":"2026-04-09T02:15:01.071204Z","steps":["trace[641615525] 'process raft request' (duration: 188.600793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:01.071732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.729804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110692"} {"level":"info","ts":"2026-04-09T02:15:01.071808Z","caller":"traceutil/trace.go:171","msg":"trace[548488981] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5501; }","duration":"187.859947ms","start":"2026-04-09T02:15:00.883933Z","end":"2026-04-09T02:15:01.071793Z","steps":["trace[548488981] 'agreement among raft nodes before linearized reading' (duration: 187.440898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:02.869593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.9971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:15:02.870003Z","caller":"traceutil/trace.go:171","msg":"trace[969303020] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"680.458874ms","start":"2026-04-09T02:15:02.189519Z","end":"2026-04-09T02:15:02.869977Z","steps":["trace[969303020] 'process raft request' (duration: 136.972582ms)","trace[969303020] 'compare' (duration: 542.766345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:15:02.870136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:15:02.189501Z","time spent":"680.591717ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:15:02.944537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676856133,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:15:03.045117Z","caller":"traceutil/trace.go:171","msg":"trace[678153763] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"850.245696ms","start":"2026-04-09T02:15:02.194815Z","end":"2026-04-09T02:15:03.04506Z","steps":["trace[678153763] 'process raft request' (duration: 775.683024ms)","trace[678153763] 'compare' (duration: 74.342938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:15:03.045408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:15:02.194799Z","time spent":"850.469672ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:15:03.047443Z","caller":"traceutil/trace.go:171","msg":"trace[1331702965] linearizableReadLoop","detail":"{readStateIndex:5727; appliedIndex:5724; }","duration":"603.680423ms","start":"2026-04-09T02:15:02.443728Z","end":"2026-04-09T02:15:03.047408Z","steps":["trace[1331702965] 'read index received' (duration: 526.778559ms)","trace[1331702965] 'applied index is now lower than readState.Index' (duration: 76.901004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:15:03.047765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.974909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:15:03.047831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.716498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:15:03.047878Z","caller":"traceutil/trace.go:171","msg":"trace[312009416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5526; }","duration":"255.80945ms","start":"2026-04-09T02:15:02.792053Z","end":"2026-04-09T02:15:03.047862Z","steps":["trace[312009416] 'agreement among raft nodes before linearized reading' (duration: 255.727078ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:03.047888Z","caller":"traceutil/trace.go:171","msg":"trace[565645483] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5526; }","duration":"604.163734ms","start":"2026-04-09T02:15:02.443693Z","end":"2026-04-09T02:15:03.047857Z","steps":["trace[565645483] 'agreement among raft nodes before linearized reading' (duration: 603.924819ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:03.047877Z","caller":"traceutil/trace.go:171","msg":"trace[1821254640] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"252.391294ms","start":"2026-04-09T02:15:02.795437Z","end":"2026-04-09T02:15:03.047828Z","steps":["trace[1821254640] 'process raft request' (duration: 251.919854ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:03.0478Z","caller":"traceutil/trace.go:171","msg":"trace[606460524] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"252.400335ms","start":"2026-04-09T02:15:02.795363Z","end":"2026-04-09T02:15:03.047763Z","steps":["trace[606460524] 'process raft request' (duration: 251.844743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:03.048022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:15:02.443676Z","time spent":"604.261996ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:15:13.543462Z","caller":"traceutil/trace.go:171","msg":"trace[2027178288] transaction","detail":"{read_only:false; response_revision:5695; number_of_response:1; }","duration":"169.689432ms","start":"2026-04-09T02:15:13.373742Z","end":"2026-04-09T02:15:13.543431Z","steps":["trace[2027178288] 'process raft request' (duration: 152.868547ms)","trace[2027178288] 'compare' (duration: 16.669212ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:13.559043Z","caller":"traceutil/trace.go:171","msg":"trace[1143300559] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"185.118475ms","start":"2026-04-09T02:15:13.373898Z","end":"2026-04-09T02:15:13.559016Z","steps":["trace[1143300559] 'process raft request' (duration: 184.90115ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:13.559262Z","caller":"traceutil/trace.go:171","msg":"trace[245776026] linearizableReadLoop","detail":"{readStateIndex:5904; appliedIndex:5900; }","duration":"180.318878ms","start":"2026-04-09T02:15:13.378924Z","end":"2026-04-09T02:15:13.559243Z","steps":["trace[245776026] 'read index received' (duration: 147.698651ms)","trace[245776026] 'applied index is now lower than readState.Index' (duration: 32.618807ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:13.559368Z","caller":"traceutil/trace.go:171","msg":"trace[1390714052] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"185.005513ms","start":"2026-04-09T02:15:13.37433Z","end":"2026-04-09T02:15:13.559335Z","steps":["trace[1390714052] 'process raft request' (duration: 184.625014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:13.559461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.515963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T02:15:13.559498Z","caller":"traceutil/trace.go:171","msg":"trace[582904572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5698; }","duration":"180.596424ms","start":"2026-04-09T02:15:13.37889Z","end":"2026-04-09T02:15:13.559486Z","steps":["trace[582904572] 'agreement among raft nodes before linearized reading' (duration: 180.453031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:13.559647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.04454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-09T02:15:13.559705Z","caller":"traceutil/trace.go:171","msg":"trace[2087036852] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5698; }","duration":"157.203713ms","start":"2026-04-09T02:15:13.402485Z","end":"2026-04-09T02:15:13.559688Z","steps":["trace[2087036852] 'agreement among raft nodes before linearized reading' (duration: 156.918587ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:13.559394Z","caller":"traceutil/trace.go:171","msg":"trace[485026417] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"184.314136ms","start":"2026-04-09T02:15:13.375034Z","end":"2026-04-09T02:15:13.559348Z","steps":["trace[485026417] 'process raft request' (duration: 184.129882ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:38.743065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-04-09T02:15:38.815532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"70.968044ms","hash":3750669435,"current-db-size-bytes":56410112,"current-db-size":"56 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T02:15:38.815691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3750669435,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-04-09T02:20:19.293761Z","caller":"traceutil/trace.go:171","msg":"trace[13646134] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"106.878857ms","start":"2026-04-09T02:20:19.186857Z","end":"2026-04-09T02:20:19.293735Z","steps":["trace[13646134] 'process raft request' (duration: 106.716983ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:20:19.349282Z","caller":"traceutil/trace.go:171","msg":"trace[1022543955] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"161.920111ms","start":"2026-04-09T02:20:19.187303Z","end":"2026-04-09T02:20:19.349223Z","steps":["trace[1022543955] 'process raft request' (duration: 156.48093ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:20:38.748623Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5865} {"level":"info","ts":"2026-04-09T02:20:38.828172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5865,"took":"78.071966ms","hash":3118759715,"current-db-size-bytes":56410112,"current-db-size":"56 MB","current-db-size-in-use-bytes":25616384,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-09T02:20:38.828246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3118759715,"revision":5865,"compact-revision":2941} {"level":"info","ts":"2026-04-09T02:20:50.673258Z","caller":"traceutil/trace.go:171","msg":"trace[810472848] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"270.224599ms","start":"2026-04-09T02:20:50.403012Z","end":"2026-04-09T02:20:50.673237Z","steps":["trace[810472848] 'process raft request' (duration: 270.082996ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:20:50.69812Z","caller":"traceutil/trace.go:171","msg":"trace[1923615055] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"266.714802ms","start":"2026-04-09T02:20:50.431376Z","end":"2026-04-09T02:20:50.698091Z","steps":["trace[1923615055] 'process raft request' (duration: 266.517177ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:20:50.698353Z","caller":"traceutil/trace.go:171","msg":"trace[1718487699] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"158.411782ms","start":"2026-04-09T02:20:50.539931Z","end":"2026-04-09T02:20:50.698342Z","steps":["trace[1718487699] 'process raft request' (duration: 158.112706ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:02.996884Z","caller":"traceutil/trace.go:171","msg":"trace[919007158] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"269.843091ms","start":"2026-04-09T02:21:02.727006Z","end":"2026-04-09T02:21:02.996849Z","steps":["trace[919007158] 'process raft request' (duration: 269.511234ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:03.004592Z","caller":"traceutil/trace.go:171","msg":"trace[1291666745] linearizableReadLoop","detail":"{readStateIndex:8643; appliedIndex:8640; }","duration":"209.779635ms","start":"2026-04-09T02:21:02.794787Z","end":"2026-04-09T02:21:03.004567Z","steps":["trace[1291666745] 'read index received' (duration: 201.777987ms)","trace[1291666745] 'applied index is now lower than readState.Index' (duration: 8.000648ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:03.004664Z","caller":"traceutil/trace.go:171","msg":"trace[1623654092] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"264.776718ms","start":"2026-04-09T02:21:02.739858Z","end":"2026-04-09T02:21:03.004635Z","steps":["trace[1623654092] 'process raft request' (duration: 264.501792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:03.004758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.948769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:03.0048Z","caller":"traceutil/trace.go:171","msg":"trace[533957330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8352; }","duration":"210.035571ms","start":"2026-04-09T02:21:02.794754Z","end":"2026-04-09T02:21:03.004789Z","steps":["trace[533957330] 'agreement among raft nodes before linearized reading' (duration: 209.915698ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:03.004749Z","caller":"traceutil/trace.go:171","msg":"trace[474056779] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"256.585666ms","start":"2026-04-09T02:21:02.748093Z","end":"2026-04-09T02:21:03.004678Z","steps":["trace[474056779] 'process raft request' (duration: 256.436682ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.079909Z","caller":"traceutil/trace.go:171","msg":"trace[1537841910] linearizableReadLoop","detail":"{readStateIndex:8645; appliedIndex:8644; }","duration":"467.310313ms","start":"2026-04-09T02:21:03.612571Z","end":"2026-04-09T02:21:04.079882Z","steps":["trace[1537841910] 'read index received' (duration: 467.104778ms)","trace[1537841910] 'applied index is now lower than readState.Index' (duration: 203.955µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:04.080136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.534977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:04.080237Z","caller":"traceutil/trace.go:171","msg":"trace[2059472691] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:8354; }","duration":"467.699271ms","start":"2026-04-09T02:21:03.612509Z","end":"2026-04-09T02:21:04.080208Z","steps":["trace[2059472691] 'agreement among raft nodes before linearized reading' (duration: 467.510487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:04.080213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.143551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T02:21:04.080257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.825043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-09T02:21:04.080298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.612473Z","time spent":"467.812404ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:21:04.080314Z","caller":"traceutil/trace.go:171","msg":"trace[1619891239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8354; }","duration":"444.930875ms","start":"2026-04-09T02:21:03.635367Z","end":"2026-04-09T02:21:04.080298Z","steps":["trace[1619891239] 'agreement among raft nodes before linearized reading' (duration: 444.776632ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.080311Z","caller":"traceutil/trace.go:171","msg":"trace[1675427986] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8354; }","duration":"446.290895ms","start":"2026-04-09T02:21:03.633998Z","end":"2026-04-09T02:21:04.080289Z","steps":["trace[1675427986] 'agreement among raft nodes before linearized reading' (duration: 446.120211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:04.080349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.635336Z","time spent":"445.005286ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:21:04.080348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.400477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T02:21:04.080363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.633984Z","time spent":"446.368086ms","remote":"127.0.0.1:33490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:21:04.080395Z","caller":"traceutil/trace.go:171","msg":"trace[574136826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8354; }","duration":"446.485379ms","start":"2026-04-09T02:21:03.633896Z","end":"2026-04-09T02:21:04.080381Z","steps":["trace[574136826] 'agreement among raft nodes before linearized reading' (duration: 446.328045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:04.080365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.10384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T02:21:04.080428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.633877Z","time spent":"446.54422ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T02:21:04.080476Z","caller":"traceutil/trace.go:171","msg":"trace[1573348760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8354; }","duration":"437.259304ms","start":"2026-04-09T02:21:03.643192Z","end":"2026-04-09T02:21:04.080451Z","steps":["trace[1573348760] 'agreement among raft nodes before linearized reading' (duration: 436.923387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:04.080169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.319419ms","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":"warn","ts":"2026-04-09T02:21:04.080543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.64318Z","time spent":"437.352696ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T02:21:04.080566Z","caller":"traceutil/trace.go:171","msg":"trace[1902210338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8354; }","duration":"452.756348ms","start":"2026-04-09T02:21:03.627799Z","end":"2026-04-09T02:21:04.080556Z","steps":["trace[1902210338] 'agreement among raft nodes before linearized reading' (duration: 452.233516ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.079956Z","caller":"traceutil/trace.go:171","msg":"trace[86812455] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"480.596038ms","start":"2026-04-09T02:21:03.599325Z","end":"2026-04-09T02:21:04.079921Z","steps":["trace[86812455] 'process raft request' (duration: 480.359693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:04.08065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.627787Z","time spent":"452.853361ms","remote":"127.0.0.1:33580","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":"warn","ts":"2026-04-09T02:21:04.080947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:03.599302Z","time spent":"481.541099ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:04.187921Z","caller":"traceutil/trace.go:171","msg":"trace[1905339391] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"101.756613ms","start":"2026-04-09T02:21:04.086137Z","end":"2026-04-09T02:21:04.187894Z","steps":["trace[1905339391] 'process raft request' (duration: 101.555888ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.205525Z","caller":"traceutil/trace.go:171","msg":"trace[1960510609] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"119.243982ms","start":"2026-04-09T02:21:04.086251Z","end":"2026-04-09T02:21:04.205495Z","steps":["trace[1960510609] 'process raft request' (duration: 119.025268ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.205772Z","caller":"traceutil/trace.go:171","msg":"trace[129498682] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"119.443216ms","start":"2026-04-09T02:21:04.086311Z","end":"2026-04-09T02:21:04.205755Z","steps":["trace[129498682] 'process raft request' (duration: 119.130779ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.206018Z","caller":"traceutil/trace.go:171","msg":"trace[775941930] transaction","detail":"{read_only:false; response_revision:8358; number_of_response:1; }","duration":"119.513248ms","start":"2026-04-09T02:21:04.086459Z","end":"2026-04-09T02:21:04.205972Z","steps":["trace[775941930] 'process raft request' (duration: 119.210551ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.206322Z","caller":"traceutil/trace.go:171","msg":"trace[1670489295] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"119.702132ms","start":"2026-04-09T02:21:04.086589Z","end":"2026-04-09T02:21:04.206291Z","steps":["trace[1670489295] 'process raft request' (duration: 119.274463ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:04.388697Z","caller":"traceutil/trace.go:171","msg":"trace[1274698039] transaction","detail":"{read_only:false; response_revision:8360; number_of_response:1; }","duration":"134.323687ms","start":"2026-04-09T02:21:04.254351Z","end":"2026-04-09T02:21:04.388674Z","steps":["trace[1274698039] 'process raft request' (duration: 134.170373ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:05.12515Z","caller":"traceutil/trace.go:171","msg":"trace[979169100] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"115.587641ms","start":"2026-04-09T02:21:05.009527Z","end":"2026-04-09T02:21:05.125115Z","steps":["trace[979169100] 'process raft request' (duration: 115.306104ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:05.125447Z","caller":"traceutil/trace.go:171","msg":"trace[1151993138] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"115.071549ms","start":"2026-04-09T02:21:05.01029Z","end":"2026-04-09T02:21:05.125362Z","steps":["trace[1151993138] 'process raft request' (duration: 114.733822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:05.264265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.046086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:21:05.264382Z","caller":"traceutil/trace.go:171","msg":"trace[982577745] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8364; }","duration":"105.286981ms","start":"2026-04-09T02:21:05.159073Z","end":"2026-04-09T02:21:05.26436Z","steps":["trace[982577745] 'count revisions from in-memory index tree' (duration: 104.881743ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:30.357514Z","caller":"traceutil/trace.go:171","msg":"trace[505970418] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"120.851457ms","start":"2026-04-09T02:21:30.236625Z","end":"2026-04-09T02:21:30.357476Z","steps":["trace[505970418] 'process raft request' (duration: 120.558631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:33.67643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676864323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:21:34.17704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676864323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:21:34.67824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695969676864323,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:21:34.9658Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.80182873s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T02:21:34.966383Z","caller":"traceutil/trace.go:171","msg":"trace[2049302756] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"1.802456164s","start":"2026-04-09T02:21:33.163868Z","end":"2026-04-09T02:21:34.966324Z","steps":["trace[2049302756] 'process raft request' (duration: 1.802205118s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.966654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.163845Z","time spent":"1.802665009s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:34.968534Z","caller":"traceutil/trace.go:171","msg":"trace[1428926849] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"1.718997167s","start":"2026-04-09T02:21:33.249495Z","end":"2026-04-09T02:21:34.968492Z","steps":["trace[1428926849] 'process raft request' (duration: 1.718896365s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:34.968608Z","caller":"traceutil/trace.go:171","msg":"trace[1452718255] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"1.720394808s","start":"2026-04-09T02:21:33.248155Z","end":"2026-04-09T02:21:34.96855Z","steps":["trace[1452718255] 'process raft request' (duration: 1.72003125s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.968799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.249482Z","time spent":"1.71915292s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:34.968914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.248137Z","time spent":"1.720587483s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:34.968759Z","caller":"traceutil/trace.go:171","msg":"trace[882876379] linearizableReadLoop","detail":"{readStateIndex:8866; appliedIndex:8862; }","duration":"1.792439211s","start":"2026-04-09T02:21:33.176218Z","end":"2026-04-09T02:21:34.968658Z","steps":["trace[882876379] 'read index received' (duration: 1.790029367s)","trace[882876379] 'applied index is now lower than readState.Index' (duration: 2.408774ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:34.969116Z","caller":"traceutil/trace.go:171","msg":"trace[1104807261] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"1.502315098s","start":"2026-04-09T02:21:33.466781Z","end":"2026-04-09T02:21:34.969096Z","steps":["trace[1104807261] 'process raft request' (duration: 1.501654044s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.46676Z","time spent":"1.502398151s","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:34.969207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792968763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:34.96927Z","caller":"traceutil/trace.go:171","msg":"trace[613259634] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8567; }","duration":"1.793077185s","start":"2026-04-09T02:21:33.176172Z","end":"2026-04-09T02:21:34.969249Z","steps":["trace[613259634] 'agreement among raft nodes before linearized reading' (duration: 1.792938632s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.176132Z","time spent":"1.793172708s","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:21:34.969347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.778072022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-09T02:21:34.969403Z","caller":"traceutil/trace.go:171","msg":"trace[1300668214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8567; }","duration":"1.778141134s","start":"2026-04-09T02:21:33.191239Z","end":"2026-04-09T02:21:34.96938Z","steps":["trace[1300668214] 'agreement among raft nodes before linearized reading' (duration: 1.777914658s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.191224Z","time spent":"1.778225045s","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T02:21:34.969577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178722993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:34.969691Z","caller":"traceutil/trace.go:171","msg":"trace[1195652939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8567; }","duration":"1.178884507s","start":"2026-04-09T02:21:33.79077Z","end":"2026-04-09T02:21:34.969655Z","steps":["trace[1195652939] 'agreement among raft nodes before linearized reading' (duration: 1.178699563s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.593136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-09T02:21:34.969705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.659467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6bdcc9bd6c-wxkxc.18a48edfa275839b\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-04-09T02:21:34.96974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.790753Z","time spent":"1.178977178s","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T02:21:34.969768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.389038ms","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-04-09T02:21:34.969797Z","caller":"traceutil/trace.go:171","msg":"trace[844368185] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6bdcc9bd6c-wxkxc.18a48edfa275839b; range_end:; response_count:1; response_revision:8567; }","duration":"720.79207ms","start":"2026-04-09T02:21:34.248978Z","end":"2026-04-09T02:21:34.96977Z","steps":["trace[844368185] 'agreement among raft nodes before linearized reading' (duration: 720.463313ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:34.969834Z","caller":"traceutil/trace.go:171","msg":"trace[1756331137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8567; }","duration":"585.48985ms","start":"2026-04-09T02:21:34.384326Z","end":"2026-04-09T02:21:34.969816Z","steps":["trace[1756331137] 'agreement among raft nodes before linearized reading' (duration: 585.272025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.846012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T02:21:34.969851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.24896Z","time spent":"720.877232ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1157,"request content":"key:\"/registry/events/openstack/barbican-api-6bdcc9bd6c-wxkxc.18a48edfa275839b\" "} {"level":"warn","ts":"2026-04-09T02:21:34.969879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.384298Z","time spent":"585.568712ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:21:34.969889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.682012ms","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":"warn","ts":"2026-04-09T02:21:34.969899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.665343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:21:34.969903Z","caller":"traceutil/trace.go:171","msg":"trace[1847486240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8567; }","duration":"573.938234ms","start":"2026-04-09T02:21:34.395942Z","end":"2026-04-09T02:21:34.96988Z","steps":["trace[1847486240] 'agreement among raft nodes before linearized reading' (duration: 573.636347ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:34.969934Z","caller":"traceutil/trace.go:171","msg":"trace[1093403418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8567; }","duration":"566.745234ms","start":"2026-04-09T02:21:34.403179Z","end":"2026-04-09T02:21:34.969925Z","steps":["trace[1093403418] 'agreement among raft nodes before linearized reading' (duration: 566.634142ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:34.969951Z","caller":"traceutil/trace.go:171","msg":"trace[259451289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8567; }","duration":"566.724944ms","start":"2026-04-09T02:21:34.403211Z","end":"2026-04-09T02:21:34.969936Z","steps":["trace[259451289] 'agreement among raft nodes before linearized reading' (duration: 566.583041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.395897Z","time spent":"574.053886ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T02:21:34.969778Z","caller":"traceutil/trace.go:171","msg":"trace[934174567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8567; }","duration":"573.78573ms","start":"2026-04-09T02:21:34.395967Z","end":"2026-04-09T02:21:34.969753Z","steps":["trace[934174567] 'agreement among raft nodes before linearized reading' (duration: 573.445102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.969985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.403172Z","time spent":"566.801816ms","remote":"127.0.0.1:33580","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":"warn","ts":"2026-04-09T02:21:34.970002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.403208Z","time spent":"566.782355ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:21:34.970013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.395962Z","time spent":"574.039535ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:21:34.970031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504038827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:21:34.970084Z","caller":"traceutil/trace.go:171","msg":"trace[1898272818] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8567; }","duration":"1.50413734s","start":"2026-04-09T02:21:33.465932Z","end":"2026-04-09T02:21:34.97007Z","steps":["trace[1898272818] 'agreement among raft nodes before linearized reading' (duration: 1.503990166s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.970119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:33.465913Z","time spent":"1.504197631s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":23,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:21:34.970171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.934985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-09T02:21:34.970172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.212408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wtmlp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T02:21:34.970221Z","caller":"traceutil/trace.go:171","msg":"trace[1743746902] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8567; }","duration":"569.988077ms","start":"2026-04-09T02:21:34.400216Z","end":"2026-04-09T02:21:34.970204Z","steps":["trace[1743746902] 'agreement among raft nodes before linearized reading' (duration: 569.884304ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:34.970228Z","caller":"traceutil/trace.go:171","msg":"trace[2143681301] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wtmlp; range_end:; response_count:1; response_revision:8567; }","duration":"564.277849ms","start":"2026-04-09T02:21:34.405937Z","end":"2026-04-09T02:21:34.970215Z","steps":["trace[2143681301] 'agreement among raft nodes before linearized reading' (duration: 564.169746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:34.970253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.400208Z","time spent":"570.036548ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T02:21:34.970268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:34.405931Z","time spent":"564.32792ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-wtmlp\" "} {"level":"warn","ts":"2026-04-09T02:21:37.385539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.078813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:21:37.385793Z","caller":"traceutil/trace.go:171","msg":"trace[1789545579] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8875; }","duration":"402.0488ms","start":"2026-04-09T02:21:36.983721Z","end":"2026-04-09T02:21:37.38577Z","steps":["trace[1789545579] 'read index received' (duration: 101.459446ms)","trace[1789545579] 'applied index is now lower than readState.Index' (duration: 300.587934ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:37.38582Z","caller":"traceutil/trace.go:171","msg":"trace[512075066] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"409.155729ms","start":"2026-04-09T02:21:36.976638Z","end":"2026-04-09T02:21:37.385794Z","steps":["trace[512075066] 'process raft request' (duration: 108.696297ms)","trace[512075066] 'compare' (duration: 299.90758ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:37.385886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.976624Z","time spent":"409.23178ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:37.385846Z","caller":"traceutil/trace.go:171","msg":"trace[1275431188] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"408.549166ms","start":"2026-04-09T02:21:36.977249Z","end":"2026-04-09T02:21:37.385798Z","steps":["trace[1275431188] 'process raft request' (duration: 408.423913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.38605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.977222Z","time spent":"408.716279ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:37.386516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.787296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T02:21:37.386563Z","caller":"traceutil/trace.go:171","msg":"trace[494065872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8578; }","duration":"402.877638ms","start":"2026-04-09T02:21:36.983672Z","end":"2026-04-09T02:21:37.38655Z","steps":["trace[494065872] 'agreement among raft nodes before linearized reading' (duration: 402.212394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.386596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.983651Z","time spent":"402.93765ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:21:37.78582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.040866ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T02:21:37.786159Z","caller":"traceutil/trace.go:171","msg":"trace[1324245163] linearizableReadLoop","detail":"{readStateIndex:8878; appliedIndex:8877; }","duration":"400.312172ms","start":"2026-04-09T02:21:37.385823Z","end":"2026-04-09T02:21:37.786136Z","steps":["trace[1324245163] 'read index received' (duration: 79.511µs)","trace[1324245163] 'applied index is now lower than readState.Index' (duration: 400.231291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:37.786382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.573118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:21:37.786421Z","caller":"traceutil/trace.go:171","msg":"trace[1600632055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8578; }","duration":"802.642729ms","start":"2026-04-09T02:21:36.983767Z","end":"2026-04-09T02:21:37.78641Z","steps":["trace[1600632055] 'agreement among raft nodes before linearized reading' (duration: 802.455715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.786467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.983762Z","time spent":"802.6968ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:21:37.786451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.612709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:21:37.786547Z","caller":"traceutil/trace.go:171","msg":"trace[138007721] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8578; }","duration":"637.731831ms","start":"2026-04-09T02:21:37.148789Z","end":"2026-04-09T02:21:37.78652Z","steps":["trace[138007721] 'agreement among raft nodes before linearized reading' (duration: 637.547227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.786618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:37.14873Z","time spent":"637.874566ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:21:37.786851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.639356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:21:37.786905Z","caller":"traceutil/trace.go:171","msg":"trace[1308988758] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8578; }","duration":"689.76385ms","start":"2026-04-09T02:21:37.097126Z","end":"2026-04-09T02:21:37.78689Z","steps":["trace[1308988758] 'agreement among raft nodes before linearized reading' (duration: 689.669907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.786948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:37.097094Z","time spent":"689.844721ms","remote":"127.0.0.1:51800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:21:37.787176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.227697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wtmlp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T02:21:37.787217Z","caller":"traceutil/trace.go:171","msg":"trace[612582567] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wtmlp; range_end:; response_count:1; response_revision:8578; }","duration":"796.290309ms","start":"2026-04-09T02:21:36.990916Z","end":"2026-04-09T02:21:37.787207Z","steps":["trace[612582567] 'agreement among raft nodes before linearized reading' (duration: 796.202357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.787252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.990905Z","time spent":"796.335699ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-wtmlp\" "} {"level":"info","ts":"2026-04-09T02:21:37.787725Z","caller":"traceutil/trace.go:171","msg":"trace[515440121] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"396.849516ms","start":"2026-04-09T02:21:37.390854Z","end":"2026-04-09T02:21:37.787703Z","steps":["trace[515440121] 'process raft request' (duration: 395.123148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.787849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:37.390824Z","time spent":"396.957758ms","remote":"127.0.0.1:33580","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-04-09T02:21:37.795625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.759941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-09T02:21:37.795658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.469846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T02:21:37.795712Z","caller":"traceutil/trace.go:171","msg":"trace[1084324592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8579; }","duration":"811.901465ms","start":"2026-04-09T02:21:36.983792Z","end":"2026-04-09T02:21:37.795693Z","steps":["trace[1084324592] 'agreement among raft nodes before linearized reading' (duration: 803.663462ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:37.795723Z","caller":"traceutil/trace.go:171","msg":"trace[1836237108] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8579; }","duration":"811.609828ms","start":"2026-04-09T02:21:36.984096Z","end":"2026-04-09T02:21:37.795706Z","steps":["trace[1836237108] 'agreement among raft nodes before linearized reading' (duration: 803.342595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.795714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.032806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T02:21:37.795762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.984066Z","time spent":"811.68452ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-09T02:21:37.79581Z","caller":"traceutil/trace.go:171","msg":"trace[1621426353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8579; }","duration":"811.165258ms","start":"2026-04-09T02:21:36.984626Z","end":"2026-04-09T02:21:37.795792Z","steps":["trace[1621426353] 'agreement among raft nodes before linearized reading' (duration: 802.801172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.795757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.983787Z","time spent":"811.959626ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:21:37.795862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.984616Z","time spent":"811.236261ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:21:37.795641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.888688ms","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-04-09T02:21:37.795924Z","caller":"traceutil/trace.go:171","msg":"trace[1686830579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8579; }","duration":"809.201036ms","start":"2026-04-09T02:21:36.986711Z","end":"2026-04-09T02:21:37.795912Z","steps":["trace[1686830579] 'agreement among raft nodes before linearized reading' (duration: 800.702036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:37.795969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.986698Z","time spent":"809.259617ms","remote":"127.0.0.1:33580","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":"warn","ts":"2026-04-09T02:21:47.985798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.6777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:21:47.985915Z","caller":"traceutil/trace.go:171","msg":"trace[90300280] linearizableReadLoop","detail":"{readStateIndex:8979; appliedIndex:8978; }","duration":"287.812991ms","start":"2026-04-09T02:21:47.698082Z","end":"2026-04-09T02:21:47.985895Z","steps":["trace[90300280] 'read index received' (duration: 159.851165ms)","trace[90300280] 'applied index is now lower than readState.Index' (duration: 127.960346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:47.986047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.979135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-dzhbn\" ","response":"range_response_count:1 size:4668"} {"level":"info","ts":"2026-04-09T02:21:47.986001Z","caller":"traceutil/trace.go:171","msg":"trace[1265340745] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"461.122535ms","start":"2026-04-09T02:21:47.524836Z","end":"2026-04-09T02:21:47.985959Z","steps":["trace[1265340745] 'process raft request' (duration: 333.18465ms)","trace[1265340745] 'compare' (duration: 127.329872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:47.986111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.893658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:47.986138Z","caller":"traceutil/trace.go:171","msg":"trace[339797684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8674; }","duration":"267.96782ms","start":"2026-04-09T02:21:47.718163Z","end":"2026-04-09T02:21:47.986131Z","steps":["trace[339797684] 'agreement among raft nodes before linearized reading' (duration: 267.927419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:47.986239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:47.524803Z","time spent":"461.301259ms","remote":"127.0.0.1:33498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4653,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:47.986071Z","caller":"traceutil/trace.go:171","msg":"trace[1660341397] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-dzhbn; range_end:; response_count:1; response_revision:8674; }","duration":"288.060526ms","start":"2026-04-09T02:21:47.698002Z","end":"2026-04-09T02:21:47.986062Z","steps":["trace[1660341397] 'agreement among raft nodes before linearized reading' (duration: 287.982425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:47.986429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.763622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T02:21:47.986477Z","caller":"traceutil/trace.go:171","msg":"trace[1172209444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8674; }","duration":"114.872705ms","start":"2026-04-09T02:21:47.871592Z","end":"2026-04-09T02:21:47.986465Z","steps":["trace[1172209444] 'agreement among raft nodes before linearized reading' (duration: 114.716302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:47.986635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.770159ms","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":"warn","ts":"2026-04-09T02:21:47.986634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.770519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-09T02:21:47.986688Z","caller":"traceutil/trace.go:171","msg":"trace[480557463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8674; }","duration":"113.846332ms","start":"2026-04-09T02:21:47.872828Z","end":"2026-04-09T02:21:47.986674Z","steps":["trace[480557463] 'agreement among raft nodes before linearized reading' (duration: 113.672808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:47.986673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.63904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-09T02:21:47.986712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.968469ms","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-04-09T02:21:47.986751Z","caller":"traceutil/trace.go:171","msg":"trace[806699018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8674; }","duration":"148.931412ms","start":"2026-04-09T02:21:47.837793Z","end":"2026-04-09T02:21:47.986724Z","steps":["trace[806699018] 'agreement among raft nodes before linearized reading' (duration: 148.575194ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:47.98681Z","caller":"traceutil/trace.go:171","msg":"trace[1703238921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8674; }","duration":"120.082641ms","start":"2026-04-09T02:21:47.8667Z","end":"2026-04-09T02:21:47.986783Z","steps":["trace[1703238921] 'agreement among raft nodes before linearized reading' (duration: 119.721473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:47.986696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.381461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:21:47.986867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.280827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wtmlp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-09T02:21:47.986966Z","caller":"traceutil/trace.go:171","msg":"trace[1453693332] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wtmlp; range_end:; response_count:1; response_revision:8674; }","duration":"108.40651ms","start":"2026-04-09T02:21:47.878537Z","end":"2026-04-09T02:21:47.986944Z","steps":["trace[1453693332] 'agreement among raft nodes before linearized reading' (duration: 107.855838ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:47.986818Z","caller":"traceutil/trace.go:171","msg":"trace[1737902681] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8674; }","duration":"110.776753ms","start":"2026-04-09T02:21:47.875995Z","end":"2026-04-09T02:21:47.986772Z","steps":["trace[1737902681] 'agreement among raft nodes before linearized reading' (duration: 110.467726ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:47.987111Z","caller":"traceutil/trace.go:171","msg":"trace[805649416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8674; }","duration":"189.702558ms","start":"2026-04-09T02:21:47.797232Z","end":"2026-04-09T02:21:47.986935Z","steps":["trace[805649416] 'agreement among raft nodes before linearized reading' (duration: 189.256619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:47.987338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.933277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T02:21:47.987408Z","caller":"traceutil/trace.go:171","msg":"trace[1362788933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8674; }","duration":"115.379387ms","start":"2026-04-09T02:21:47.871993Z","end":"2026-04-09T02:21:47.987372Z","steps":["trace[1362788933] 'agreement among raft nodes before linearized reading' (duration: 114.253991ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.112413Z","caller":"traceutil/trace.go:171","msg":"trace[601050714] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"120.382527ms","start":"2026-04-09T02:21:47.992Z","end":"2026-04-09T02:21:48.112382Z","steps":["trace[601050714] 'process raft request' (duration: 120.290385ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.112533Z","caller":"traceutil/trace.go:171","msg":"trace[2048468115] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"120.522141ms","start":"2026-04-09T02:21:47.991926Z","end":"2026-04-09T02:21:48.112448Z","steps":["trace[2048468115] 'process raft request' (duration: 95.30017ms)","trace[2048468115] 'compare' (duration: 24.916434ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:48.113195Z","caller":"traceutil/trace.go:171","msg":"trace[2016446786] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"119.906746ms","start":"2026-04-09T02:21:47.993244Z","end":"2026-04-09T02:21:48.11315Z","steps":["trace[2016446786] 'process raft request' (duration: 119.092488ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.113415Z","caller":"traceutil/trace.go:171","msg":"trace[1530732406] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"120.044509ms","start":"2026-04-09T02:21:47.993342Z","end":"2026-04-09T02:21:48.113386Z","steps":["trace[1530732406] 'process raft request' (duration: 119.589098ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.113776Z","caller":"traceutil/trace.go:171","msg":"trace[462430611] linearizableReadLoop","detail":"{readStateIndex:8985; appliedIndex:8979; }","duration":"119.594689ms","start":"2026-04-09T02:21:47.994161Z","end":"2026-04-09T02:21:48.113756Z","steps":["trace[462430611] 'read index received' (duration: 15.232098ms)","trace[462430611] 'applied index is now lower than readState.Index' (duration: 104.361191ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:48.113916Z","caller":"traceutil/trace.go:171","msg":"trace[2144944633] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"116.321098ms","start":"2026-04-09T02:21:47.997581Z","end":"2026-04-09T02:21:48.113902Z","steps":["trace[2144944633] 'process raft request' (duration: 116.067412ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.11399Z","caller":"traceutil/trace.go:171","msg":"trace[580388035] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"120.617422ms","start":"2026-04-09T02:21:47.993359Z","end":"2026-04-09T02:21:48.113977Z","steps":["trace[580388035] 'process raft request' (duration: 119.918836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:48.114172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.260093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-dzhbn\" ","response":"range_response_count:1 size:4673"} {"level":"info","ts":"2026-04-09T02:21:48.114231Z","caller":"traceutil/trace.go:171","msg":"trace[1680501052] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-dzhbn; range_end:; response_count:1; response_revision:8681; }","duration":"119.387425ms","start":"2026-04-09T02:21:47.994823Z","end":"2026-04-09T02:21:48.114211Z","steps":["trace[1680501052] 'agreement among raft nodes before linearized reading' (duration: 119.232032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:48.11431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.950786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-dzhbn\" ","response":"range_response_count:1 size:4673"} {"level":"info","ts":"2026-04-09T02:21:48.114365Z","caller":"traceutil/trace.go:171","msg":"trace[866021310] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-dzhbn; range_end:; response_count:1; response_revision:8681; }","duration":"119.044888ms","start":"2026-04-09T02:21:47.995306Z","end":"2026-04-09T02:21:48.114351Z","steps":["trace[866021310] 'agreement among raft nodes before linearized reading' (duration: 118.926705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:48.114453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.296805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228486"} {"level":"info","ts":"2026-04-09T02:21:48.114494Z","caller":"traceutil/trace.go:171","msg":"trace[83207013] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8681; }","duration":"120.377197ms","start":"2026-04-09T02:21:47.994107Z","end":"2026-04-09T02:21:48.114484Z","steps":["trace[83207013] 'agreement among raft nodes before linearized reading' (duration: 119.917287ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:49.421404Z","caller":"traceutil/trace.go:171","msg":"trace[1940958779] linearizableReadLoop","detail":"{readStateIndex:8994; appliedIndex:8993; }","duration":"262.844984ms","start":"2026-04-09T02:21:49.15852Z","end":"2026-04-09T02:21:49.421365Z","steps":["trace[1940958779] 'read index received' (duration: 7.073216ms)","trace[1940958779] 'applied index is now lower than readState.Index' (duration: 255.770088ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:49.421447Z","caller":"traceutil/trace.go:171","msg":"trace[1698489602] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"340.942461ms","start":"2026-04-09T02:21:49.080467Z","end":"2026-04-09T02:21:49.42141Z","steps":["trace[1698489602] 'process raft request' (duration: 340.624654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.421606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.080443Z","time spent":"341.079125ms","remote":"127.0.0.1:38902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:49.421755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.381972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-04-09T02:21:49.421801Z","caller":"traceutil/trace.go:171","msg":"trace[1286223977] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8689; }","duration":"198.510035ms","start":"2026-04-09T02:21:49.22328Z","end":"2026-04-09T02:21:49.42179Z","steps":["trace[1286223977] 'agreement among raft nodes before linearized reading' (duration: 198.287889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.421909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.370017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-09T02:21:49.422008Z","caller":"traceutil/trace.go:171","msg":"trace[234171037] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:8689; }","duration":"263.536311ms","start":"2026-04-09T02:21:49.158443Z","end":"2026-04-09T02:21:49.421979Z","steps":["trace[234171037] 'agreement among raft nodes before linearized reading' (duration: 263.10717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.852813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.384734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:21:49.852958Z","caller":"traceutil/trace.go:171","msg":"trace[1432951004] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"422.302161ms","start":"2026-04-09T02:21:49.430632Z","end":"2026-04-09T02:21:49.852934Z","steps":["trace[1432951004] 'process raft request' (duration: 150.662371ms)","trace[1432951004] 'compare' (duration: 271.159229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:49.853026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.43061Z","time spent":"422.383704ms","remote":"127.0.0.1:33396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:49.943443Z","caller":"traceutil/trace.go:171","msg":"trace[1509026258] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"504.10436ms","start":"2026-04-09T02:21:49.439298Z","end":"2026-04-09T02:21:49.943402Z","steps":["trace[1509026258] 'process raft request' (duration: 503.917456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.943635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.439279Z","time spent":"504.243514ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:49.943887Z","caller":"traceutil/trace.go:171","msg":"trace[1012914588] linearizableReadLoop","detail":"{readStateIndex:8997; appliedIndex:8995; }","duration":"279.369223ms","start":"2026-04-09T02:21:49.664467Z","end":"2026-04-09T02:21:49.943836Z","steps":["trace[1012914588] 'read index received' (duration: 278.826821ms)","trace[1012914588] 'applied index is now lower than readState.Index' (duration: 539.982µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:49.943954Z","caller":"traceutil/trace.go:171","msg":"trace[15636299] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"485.487996ms","start":"2026-04-09T02:21:49.458454Z","end":"2026-04-09T02:21:49.943942Z","steps":["trace[15636299] 'process raft request' (duration: 484.912943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.944011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.458436Z","time spent":"485.543838ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:49.944102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.604968ms","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-04-09T02:21:49.944135Z","caller":"traceutil/trace.go:171","msg":"trace[119373654] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8692; }","duration":"279.71179ms","start":"2026-04-09T02:21:49.664414Z","end":"2026-04-09T02:21:49.944126Z","steps":["trace[119373654] 'agreement among raft nodes before linearized reading' (duration: 279.593287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:50.122148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.626873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:21:50.122359Z","caller":"traceutil/trace.go:171","msg":"trace[904979845] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"217.022267ms","start":"2026-04-09T02:21:49.905289Z","end":"2026-04-09T02:21:50.122311Z","steps":["trace[904979845] 'process raft request' (duration: 56.122998ms)","trace[904979845] 'compare' (duration: 160.412838ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:50.122484Z","caller":"traceutil/trace.go:171","msg":"trace[1623349077] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"137.425027ms","start":"2026-04-09T02:21:49.985035Z","end":"2026-04-09T02:21:50.12246Z","steps":["trace[1623349077] 'process raft request' (duration: 137.364485ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.122515Z","caller":"traceutil/trace.go:171","msg":"trace[1832413937] linearizableReadLoop","detail":"{readStateIndex:8998; appliedIndex:8997; }","duration":"178.413628ms","start":"2026-04-09T02:21:49.943971Z","end":"2026-04-09T02:21:50.122385Z","steps":["trace[1832413937] 'read index received' (duration: 17.363226ms)","trace[1832413937] 'applied index is now lower than readState.Index' (duration: 161.045782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:50.122786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.009919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:50.122538Z","caller":"traceutil/trace.go:171","msg":"trace[622096833] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"159.145529ms","start":"2026-04-09T02:21:49.963354Z","end":"2026-04-09T02:21:50.1225Z","steps":["trace[622096833] 'process raft request' (duration: 158.946135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:50.123363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.531388ms","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-04-09T02:21:50.123406Z","caller":"traceutil/trace.go:171","msg":"trace[612177718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8695; }","duration":"110.61591ms","start":"2026-04-09T02:21:50.012779Z","end":"2026-04-09T02:21:50.123395Z","steps":["trace[612177718] 'agreement among raft nodes before linearized reading' (duration: 109.960396ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.123673Z","caller":"traceutil/trace.go:171","msg":"trace[1140437298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8695; }","duration":"330.198333ms","start":"2026-04-09T02:21:49.792659Z","end":"2026-04-09T02:21:50.122857Z","steps":["trace[1140437298] 'agreement among raft nodes before linearized reading' (duration: 329.993539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:50.123818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.792615Z","time spent":"331.154974ms","remote":"127.0.0.1:33308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T02:21:50.34633Z","caller":"traceutil/trace.go:171","msg":"trace[954604222] transaction","detail":"{read_only:false; response_revision:8697; number_of_response:1; }","duration":"218.559641ms","start":"2026-04-09T02:21:50.127749Z","end":"2026-04-09T02:21:50.346308Z","steps":["trace[954604222] 'process raft request' (duration: 218.331006ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.346753Z","caller":"traceutil/trace.go:171","msg":"trace[1042285425] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"218.744814ms","start":"2026-04-09T02:21:50.127997Z","end":"2026-04-09T02:21:50.346742Z","steps":["trace[1042285425] 'process raft request' (duration: 218.253423ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.346787Z","caller":"traceutil/trace.go:171","msg":"trace[1851531793] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"218.162311ms","start":"2026-04-09T02:21:50.128599Z","end":"2026-04-09T02:21:50.346761Z","steps":["trace[1851531793] 'process raft request' (duration: 218.07924ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.346923Z","caller":"traceutil/trace.go:171","msg":"trace[1041645966] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"217.551709ms","start":"2026-04-09T02:21:50.129361Z","end":"2026-04-09T02:21:50.346913Z","steps":["trace[1041645966] 'process raft request' (duration: 217.359315ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.34692Z","caller":"traceutil/trace.go:171","msg":"trace[1423706390] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"218.292094ms","start":"2026-04-09T02:21:50.128505Z","end":"2026-04-09T02:21:50.346797Z","steps":["trace[1423706390] 'process raft request' (duration: 218.131081ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.347091Z","caller":"traceutil/trace.go:171","msg":"trace[1607885460] linearizableReadLoop","detail":"{readStateIndex:9005; appliedIndex:9000; }","duration":"218.53745ms","start":"2026-04-09T02:21:50.128541Z","end":"2026-04-09T02:21:50.347078Z","steps":["trace[1607885460] 'read index received' (duration: 82.662068ms)","trace[1607885460] 'applied index is now lower than readState.Index' (duration: 135.874172ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:50.347135Z","caller":"traceutil/trace.go:171","msg":"trace[1665055254] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"217.676991ms","start":"2026-04-09T02:21:50.129417Z","end":"2026-04-09T02:21:50.347094Z","steps":["trace[1665055254] 'process raft request' (duration: 217.446246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:50.347392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.806236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-09T02:21:50.348641Z","caller":"traceutil/trace.go:171","msg":"trace[5658107] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:8703; }","duration":"218.97944ms","start":"2026-04-09T02:21:50.128518Z","end":"2026-04-09T02:21:50.347498Z","steps":["trace[5658107] 'agreement among raft nodes before linearized reading' (duration: 218.624452ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.349045Z","caller":"traceutil/trace.go:171","msg":"trace[1868762714] transaction","detail":"{read_only:false; response_revision:8699; number_of_response:1; }","duration":"218.792625ms","start":"2026-04-09T02:21:50.128048Z","end":"2026-04-09T02:21:50.346841Z","steps":["trace[1868762714] 'process raft request' (duration: 218.478518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:50.349933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.680528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228466"} {"level":"info","ts":"2026-04-09T02:21:50.35006Z","caller":"traceutil/trace.go:171","msg":"trace[1639849679] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8703; }","duration":"220.820961ms","start":"2026-04-09T02:21:50.129192Z","end":"2026-04-09T02:21:50.350013Z","steps":["trace[1639849679] 'agreement among raft nodes before linearized reading' (duration: 217.987028ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:51.449569Z","caller":"traceutil/trace.go:171","msg":"trace[1274213927] linearizableReadLoop","detail":"{readStateIndex:9010; appliedIndex:9009; }","duration":"121.374839ms","start":"2026-04-09T02:21:51.328154Z","end":"2026-04-09T02:21:51.449529Z","steps":["trace[1274213927] 'read index received' (duration: 121.088343ms)","trace[1274213927] 'applied index is now lower than readState.Index' (duration: 285.046µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:51.449618Z","caller":"traceutil/trace.go:171","msg":"trace[1533210363] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"276.422186ms","start":"2026-04-09T02:21:51.173156Z","end":"2026-04-09T02:21:51.449578Z","steps":["trace[1533210363] 'process raft request' (duration: 276.12699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:51.44978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.603704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:51.449882Z","caller":"traceutil/trace.go:171","msg":"trace[663564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8705; }","duration":"121.767298ms","start":"2026-04-09T02:21:51.32809Z","end":"2026-04-09T02:21:51.449857Z","steps":["trace[663564] 'agreement among raft nodes before linearized reading' (duration: 121.590383ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:22:38.317993Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000250b.wal"} {"level":"info","ts":"2026-04-09T02:23:02.333388Z","caller":"traceutil/trace.go:171","msg":"trace[934397683] transaction","detail":"{read_only:false; response_revision:9412; number_of_response:1; }","duration":"104.204587ms","start":"2026-04-09T02:23:02.229161Z","end":"2026-04-09T02:23:02.333365Z","steps":["trace[934397683] 'process raft request' (duration: 104.078155ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:23:37.431725Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"344940fc142d902f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T02:23:37.438004Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T02:23:37.438141Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T02:24:58.877501Z","caller":"traceutil/trace.go:171","msg":"trace[2086491629] transaction","detail":"{read_only:false; response_revision:10242; number_of_response:1; }","duration":"103.608074ms","start":"2026-04-09T02:24:58.773856Z","end":"2026-04-09T02:24:58.877464Z","steps":["trace[2086491629] 'process raft request' (duration: 89.219774ms)","trace[2086491629] 'compare' (duration: 14.093784ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:25:38.755351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8085} {"level":"info","ts":"2026-04-09T02:25:38.821854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8085,"took":"63.903471ms","hash":3627211661,"current-db-size-bytes":56410112,"current-db-size":"56 MB","current-db-size-in-use-bytes":29077504,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T02:25:38.821977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3627211661,"revision":8085,"compact-revision":5865} {"level":"info","ts":"2026-04-09T02:30:38.765989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10592} {"level":"info","ts":"2026-04-09T02:30:38.849663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10592,"took":"80.601218ms","hash":2325231075,"current-db-size-bytes":56410112,"current-db-size":"56 MB","current-db-size-in-use-bytes":21209088,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-09T02:30:38.84974Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2325231075,"revision":10592,"compact-revision":8085}