{"level":"warn","ts":"2026-04-14T10:47:03.195695Z","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-14T10:47:03.195799Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.140: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.19.213.140:2380","--initial-cluster=instance=https://199.19.213.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.140: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-14T10:47:03.195874Z","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-14T10:47:03.195897Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-04-14T10:47:03.195924Z","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-14T10:47:03.204787Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"]} {"level":"info","ts":"2026-04-14T10:47:03.204933Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.140: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-14T10:47:03.211913Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.974116ms"} {"level":"info","ts":"2026-04-14T10:47:03.220234Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6366444c8cd268d","cluster-id":"b92fe020fa22927c"} {"level":"info","ts":"2026-04-14T10:47:03.220366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T10:47:03.220407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 0"} {"level":"info","ts":"2026-04-14T10:47:03.220435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6366444c8cd268d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T10:47:03.220468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became follower at term 1"} {"level":"info","ts":"2026-04-14T10:47:03.22051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"warn","ts":"2026-04-14T10:47:03.229247Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T10:47:03.236532Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T10:47:03.238012Z","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-14T10:47:03.240306Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6366444c8cd268d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T10:47:03.240481Z","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-14T10:47:03.240576Z","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-14T10:47:03.240515Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6366444c8cd268d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T10:47:03.24059Z","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-14T10:47:03.244493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d switched to configuration voters=(13129792010234701453)"} {"level":"info","ts":"2026-04-14T10:47:03.244506Z","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-14T10:47:03.244724Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6366444c8cd268d","initial-advertise-peer-urls":["https://199.19.213.140:2380"],"listen-peer-urls":["https://199.19.213.140:2380"],"advertise-client-urls":["https://199.19.213.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T10:47:03.244668Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","added-peer-id":"b6366444c8cd268d","added-peer-peer-urls":["https://199.19.213.140:2380"]} {"level":"info","ts":"2026-04-14T10:47:03.244754Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T10:47:03.244851Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-04-14T10:47:03.244894Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.140:2380"} {"level":"info","ts":"2026-04-14T10:47:03.521009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T10:47:03.52135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T10:47:03.521407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgPreVoteResp from b6366444c8cd268d at term 1"} {"level":"info","ts":"2026-04-14T10:47:03.521587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became candidate at term 2"} {"level":"info","ts":"2026-04-14T10:47:03.521675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d received MsgVoteResp from b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-04-14T10:47:03.521718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6366444c8cd268d became leader at term 2"} {"level":"info","ts":"2026-04-14T10:47:03.521742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6366444c8cd268d elected leader b6366444c8cd268d at term 2"} {"level":"info","ts":"2026-04-14T10:47:03.523194Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:03.524837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:47:03.524833Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6366444c8cd268d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.140:2379]}","request-path":"/0/members/b6366444c8cd268d/attributes","cluster-id":"b92fe020fa22927c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T10:47:03.524995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:47:03.525365Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b92fe020fa22927c","local-member-id":"b6366444c8cd268d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:03.525368Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T10:47:03.525434Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T10:47:03.525601Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:03.525667Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:47:03.527955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T10:47:03.528634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.140:2379"} {"level":"warn","ts":"2026-04-14T10:47:07.375472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778049768068298065,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:07.39087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.387722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:07.391057Z","caller":"traceutil/trace.go:171","msg":"trace[568161338] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"1.275701172s","start":"2026-04-14T10:47:06.11529Z","end":"2026-04-14T10:47:07.390991Z","steps":["trace[568161338] 'process raft request' (duration: 711.564235ms)","trace[568161338] 'compare' (duration: 563.234539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:07.391135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.115249Z","time spent":"1.275861744s","remote":"127.0.0.1:37654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":709,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:07.439474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.438639Z","time spent":"1.000829237s","remote":"127.0.0.1:37418","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-14T10:47:07.443138Z","caller":"traceutil/trace.go:171","msg":"trace[206439395] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"792.352473ms","start":"2026-04-14T10:47:06.650742Z","end":"2026-04-14T10:47:07.443094Z","steps":["trace[206439395] 'process raft request' (duration: 788.579738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.443328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.650712Z","time spent":"792.512287ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:07.443852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.956584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:07.443928Z","caller":"traceutil/trace.go:171","msg":"trace[1914425158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:133; }","duration":"569.068365ms","start":"2026-04-14T10:47:06.874835Z","end":"2026-04-14T10:47:07.443903Z","steps":["trace[1914425158] 'agreement among raft nodes before linearized reading' (duration: 568.328701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.443967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.874813Z","time spent":"569.144537ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:47:07.444763Z","caller":"traceutil/trace.go:171","msg":"trace[1102099684] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:135; }","duration":"568.146637ms","start":"2026-04-14T10:47:06.874889Z","end":"2026-04-14T10:47:07.443036Z","steps":["trace[1102099684] 'read index received' (duration: 564.246759ms)","trace[1102099684] 'applied index is now lower than readState.Index' (duration: 3.897778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:07.445036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.467566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:07.445077Z","caller":"traceutil/trace.go:171","msg":"trace[1218681566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:133; }","duration":"542.530348ms","start":"2026-04-14T10:47:06.902531Z","end":"2026-04-14T10:47:07.445062Z","steps":["trace[1218681566] 'agreement among raft nodes before linearized reading' (duration: 542.472187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.4451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.863837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-14T10:47:07.445132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.902495Z","time spent":"542.631459ms","remote":"127.0.0.1:37368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:47:07.445156Z","caller":"traceutil/trace.go:171","msg":"trace[215013004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:133; }","duration":"503.930588ms","start":"2026-04-14T10:47:06.9412Z","end":"2026-04-14T10:47:07.445131Z","steps":["trace[215013004] 'agreement among raft nodes before linearized reading' (duration: 503.806376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.4452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.94118Z","time spent":"504.00893ms","remote":"127.0.0.1:37574","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-14T10:47:07.445153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.269835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-14T10:47:07.445314Z","caller":"traceutil/trace.go:171","msg":"trace[233221699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:133; }","duration":"504.452778ms","start":"2026-04-14T10:47:06.940827Z","end":"2026-04-14T10:47:07.445279Z","steps":["trace[233221699] 'agreement among raft nodes before linearized reading' (duration: 504.189183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:07.445366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:06.940811Z","time spent":"504.541341ms","remote":"127.0.0.1:37574","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-14T10:47:08.674655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.75963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:08.674901Z","caller":"traceutil/trace.go:171","msg":"trace[1844948088] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"1.228510352s","start":"2026-04-14T10:47:07.446346Z","end":"2026-04-14T10:47:08.674856Z","steps":["trace[1844948088] 'process raft request' (duration: 545.477656ms)","trace[1844948088] 'compare' (duration: 682.608477ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:08.674991Z","caller":"traceutil/trace.go:171","msg":"trace[277887671] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:137; }","duration":"759.786605ms","start":"2026-04-14T10:47:07.915184Z","end":"2026-04-14T10:47:08.674971Z","steps":["trace[277887671] 'read index received' (duration: 76.731309ms)","trace[277887671] 'applied index is now lower than readState.Index' (duration: 683.054026ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:08.674996Z","caller":"traceutil/trace.go:171","msg":"trace[768496540] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"1.227264397s","start":"2026-04-14T10:47:07.447698Z","end":"2026-04-14T10:47:08.674962Z","steps":["trace[768496540] 'process raft request' (duration: 1.227097024s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:08.675057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:07.446326Z","time spent":"1.228655124s","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:08.675083Z","caller":"traceutil/trace.go:171","msg":"trace[612841806] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"1.225713876s","start":"2026-04-14T10:47:07.449348Z","end":"2026-04-14T10:47:08.675062Z","steps":["trace[612841806] 'process raft request' (duration: 1.225493802s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:08.675123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:07.447668Z","time spent":"1.22739078s","remote":"127.0.0.1:37654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":975,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:08.675144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.957108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:08.675185Z","caller":"traceutil/trace.go:171","msg":"trace[2082504542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:137; }","duration":"760.04259ms","start":"2026-04-14T10:47:07.915129Z","end":"2026-04-14T10:47:08.675172Z","steps":["trace[2082504542] 'agreement among raft nodes before linearized reading' (duration: 759.909338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:08.675239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:07.915108Z","time spent":"760.120771ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T10:47:08.67541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:07.449333Z","time spent":"1.225791227s","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:08.67542Z","caller":"traceutil/trace.go:171","msg":"trace[2091849272] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"1.225616695s","start":"2026-04-14T10:47:07.449788Z","end":"2026-04-14T10:47:08.675404Z","steps":["trace[2091849272] 'process raft request' (duration: 1.225122985s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:08.675524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:07.449779Z","time spent":"1.225697256s","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:08.676396Z","caller":"traceutil/trace.go:171","msg":"trace[1227252616] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"322.868161ms","start":"2026-04-14T10:47:08.353511Z","end":"2026-04-14T10:47:08.676379Z","steps":["trace[1227252616] 'process raft request' (duration: 322.726638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:08.676489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:08.353477Z","time spent":"322.973013ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:39.332284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778049768068298884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:39.689252Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125650603s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T10:47:39.689617Z","caller":"traceutil/trace.go:171","msg":"trace[1694013501] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.126077962s","start":"2026-04-14T10:47:38.563516Z","end":"2026-04-14T10:47:39.689593Z","steps":["trace[1694013501] 'process raft request' (duration: 1.125940079s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.689735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.563498Z","time spent":"1.126178423s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.697253Z","caller":"traceutil/trace.go:171","msg":"trace[640551235] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.118307498s","start":"2026-04-14T10:47:38.578931Z","end":"2026-04-14T10:47:39.697239Z","steps":["trace[640551235] 'process raft request' (duration: 1.118127925s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.697326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.578915Z","time spent":"1.118381079s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.6974Z","caller":"traceutil/trace.go:171","msg":"trace[1893809745] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.100494542s","start":"2026-04-14T10:47:38.596899Z","end":"2026-04-14T10:47:39.697394Z","steps":["trace[1893809745] 'process raft request' (duration: 1.100279308s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.697431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.596873Z","time spent":"1.100544833s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.697457Z","caller":"traceutil/trace.go:171","msg":"trace[2143603998] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.118339229s","start":"2026-04-14T10:47:38.579034Z","end":"2026-04-14T10:47:39.697374Z","steps":["trace[2143603998] 'process raft request' (duration: 1.118111364s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:39.697543Z","caller":"traceutil/trace.go:171","msg":"trace[550267794] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.086939842s","start":"2026-04-14T10:47:38.610557Z","end":"2026-04-14T10:47:39.697497Z","steps":["trace[550267794] 'process raft request' (duration: 1.086699167s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.697601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.579028Z","time spent":"1.118521362s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:39.697694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.61054Z","time spent":"1.087095295s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.698108Z","caller":"traceutil/trace.go:171","msg":"trace[427433213] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.085618606s","start":"2026-04-14T10:47:38.612462Z","end":"2026-04-14T10:47:39.698081Z","steps":["trace[427433213] 'process raft request' (duration: 1.084823041s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.698228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.612454Z","time spent":"1.085721318s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.698239Z","caller":"traceutil/trace.go:171","msg":"trace[211585803] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.061964744s","start":"2026-04-14T10:47:38.636264Z","end":"2026-04-14T10:47:39.698229Z","steps":["trace[211585803] 'process raft request' (duration: 1.06171652s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.698321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.636237Z","time spent":"1.062063216s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.698399Z","caller":"traceutil/trace.go:171","msg":"trace[1136778203] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.085658277s","start":"2026-04-14T10:47:38.612733Z","end":"2026-04-14T10:47:39.698392Z","steps":["trace[1136778203] 'process raft request' (duration: 1.085216598s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.698457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.612727Z","time spent":"1.085711218s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.698563Z","caller":"traceutil/trace.go:171","msg":"trace[495454982] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.042196561s","start":"2026-04-14T10:47:38.65636Z","end":"2026-04-14T10:47:39.698557Z","steps":["trace[495454982] 'process raft request' (duration: 1.04166399s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.698621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.656342Z","time spent":"1.042263302s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.698728Z","caller":"traceutil/trace.go:171","msg":"trace[1390528826] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.023836375s","start":"2026-04-14T10:47:38.674866Z","end":"2026-04-14T10:47:39.698702Z","steps":["trace[1390528826] 'process raft request' (duration: 1.023194912s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.69885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.674843Z","time spent":"1.023951886s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.699107Z","caller":"traceutil/trace.go:171","msg":"trace[141870316] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.023008518s","start":"2026-04-14T10:47:38.676087Z","end":"2026-04-14T10:47:39.699095Z","steps":["trace[141870316] 'process raft request' (duration: 1.022013509s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.699156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.676054Z","time spent":"1.02308242s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.699243Z","caller":"traceutil/trace.go:171","msg":"trace[1419406771] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:538; }","duration":"867.690904ms","start":"2026-04-14T10:47:38.831545Z","end":"2026-04-14T10:47:39.699236Z","steps":["trace[1419406771] 'read index received' (duration: 858.017672ms)","trace[1419406771] 'applied index is now lower than readState.Index' (duration: 9.672382ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:39.69929Z","caller":"traceutil/trace.go:171","msg":"trace[1942772286] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"887.679512ms","start":"2026-04-14T10:47:38.811604Z","end":"2026-04-14T10:47:39.699284Z","steps":["trace[1942772286] 'process raft request' (duration: 887.438187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.699319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.811586Z","time spent":"887.720323ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:39.699461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.919928ms","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-14T10:47:39.699524Z","caller":"traceutil/trace.go:171","msg":"trace[1171439745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"868.01328ms","start":"2026-04-14T10:47:38.831499Z","end":"2026-04-14T10:47:39.699513Z","steps":["trace[1171439745] 'agreement among raft nodes before linearized reading' (duration: 867.904288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.703208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.831487Z","time spent":"871.691294ms","remote":"127.0.0.1:37574","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-14T10:47:39.707077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.615622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:39.707066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.771657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T10:47:39.707113Z","caller":"traceutil/trace.go:171","msg":"trace[1061164563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"499.683113ms","start":"2026-04-14T10:47:39.207419Z","end":"2026-04-14T10:47:39.707102Z","steps":["trace[1061164563] 'agreement among raft nodes before linearized reading' (duration: 499.636362ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:39.707129Z","caller":"traceutil/trace.go:171","msg":"trace[680504097] range","detail":"{range_begin:/registry/masterleases/199.19.213.140; range_end:; response_count:1; response_revision:532; }","duration":"602.862698ms","start":"2026-04-14T10:47:39.104254Z","end":"2026-04-14T10:47:39.707117Z","steps":["trace[680504097] 'agreement among raft nodes before linearized reading' (duration: 602.768786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.707181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.652748ms","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-14T10:47:39.707134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:39.207394Z","time spent":"499.735944ms","remote":"127.0.0.1:37368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:47:39.707202Z","caller":"traceutil/trace.go:171","msg":"trace[1033369570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"807.71284ms","start":"2026-04-14T10:47:38.899485Z","end":"2026-04-14T10:47:39.707198Z","steps":["trace[1033369570] 'agreement among raft nodes before linearized reading' (duration: 807.677309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.707195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.045676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-14T10:47:39.70722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.899467Z","time spent":"807.75006ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T10:47:39.707237Z","caller":"traceutil/trace.go:171","msg":"trace[24180841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"678.128508ms","start":"2026-04-14T10:47:39.029095Z","end":"2026-04-14T10:47:39.707223Z","steps":["trace[24180841] 'agreement among raft nodes before linearized reading' (duration: 677.996106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.707267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:39.029073Z","time spent":"678.189119ms","remote":"127.0.0.1:37574","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-14T10:47:39.707183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:39.10424Z","time spent":"602.9357ms","remote":"127.0.0.1:37390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.140\" "} {"level":"warn","ts":"2026-04-14T10:47:39.707628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.800916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T10:47:39.707652Z","caller":"traceutil/trace.go:171","msg":"trace[126092465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"871.865337ms","start":"2026-04-14T10:47:38.83578Z","end":"2026-04-14T10:47:39.707646Z","steps":["trace[126092465] 'agreement among raft nodes before linearized reading' (duration: 871.152134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.707675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.835761Z","time spent":"871.909678ms","remote":"127.0.0.1:37574","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-14T10:47:46.002253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.841297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T10:47:46.002359Z","caller":"traceutil/trace.go:171","msg":"trace[2035976084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"252.979809ms","start":"2026-04-14T10:47:45.749357Z","end":"2026-04-14T10:47:46.002337Z","steps":["trace[2035976084] 'range keys from in-memory index tree' (duration: 252.578321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.00244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.083381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-14T10:47:46.002469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.267085ms","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-14T10:47:46.002505Z","caller":"traceutil/trace.go:171","msg":"trace[1416362780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"264.190542ms","start":"2026-04-14T10:47:45.738299Z","end":"2026-04-14T10:47:46.002489Z","steps":["trace[1416362780] 'range keys from in-memory index tree' (duration: 263.935998ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:46.002554Z","caller":"traceutil/trace.go:171","msg":"trace[1227981283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"253.380177ms","start":"2026-04-14T10:47:45.749152Z","end":"2026-04-14T10:47:46.002532Z","steps":["trace[1227981283] 'range keys from in-memory index tree' (duration: 253.126462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.002314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.622192ms","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-14T10:47:46.002679Z","caller":"traceutil/trace.go:171","msg":"trace[1534117145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"251.02873ms","start":"2026-04-14T10:47:45.751626Z","end":"2026-04-14T10:47:46.002654Z","steps":["trace[1534117145] 'range keys from in-memory index tree' (duration: 250.471659ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:46.112531Z","caller":"traceutil/trace.go:171","msg":"trace[283158849] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"351.735027ms","start":"2026-04-14T10:47:45.760765Z","end":"2026-04-14T10:47:46.1125Z","steps":["trace[283158849] 'process raft request' (duration: 351.099583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.112694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:45.76074Z","time spent":"351.869699ms","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:46.394808Z","caller":"traceutil/trace.go:171","msg":"trace[890752120] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"478.088473ms","start":"2026-04-14T10:47:45.916668Z","end":"2026-04-14T10:47:46.394756Z","steps":["trace[890752120] 'process raft request' (duration: 441.171408ms)","trace[890752120] 'compare' (duration: 36.349424ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:46.394817Z","caller":"traceutil/trace.go:171","msg":"trace[1283322801] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"387.867776ms","start":"2026-04-14T10:47:46.006909Z","end":"2026-04-14T10:47:46.394777Z","steps":["trace[1283322801] 'process raft request' (duration: 387.684103ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:46.39496Z","caller":"traceutil/trace.go:171","msg":"trace[1288474976] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"386.887327ms","start":"2026-04-14T10:47:46.00806Z","end":"2026-04-14T10:47:46.394948Z","steps":["trace[1288474976] 'process raft request' (duration: 386.652462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:45.916645Z","time spent":"478.250866ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:46.395047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.006866Z","time spent":"388.05522ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:46.395075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.008046Z","time spent":"386.947469ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:46.395363Z","caller":"traceutil/trace.go:171","msg":"trace[1773590425] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"387.103083ms","start":"2026-04-14T10:47:46.008247Z","end":"2026-04-14T10:47:46.39535Z","steps":["trace[1773590425] 'process raft request' (duration: 386.649633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.395442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.008236Z","time spent":"387.168294ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:46.395496Z","caller":"traceutil/trace.go:171","msg":"trace[1121842712] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"386.580891ms","start":"2026-04-14T10:47:46.008905Z","end":"2026-04-14T10:47:46.395486Z","steps":["trace[1121842712] 'process raft request' (duration: 386.302076ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:46.395481Z","caller":"traceutil/trace.go:171","msg":"trace[521545611] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:649; }","duration":"357.320719ms","start":"2026-04-14T10:47:46.038136Z","end":"2026-04-14T10:47:46.395457Z","steps":["trace[521545611] 'read index received' (duration: 73.549715ms)","trace[521545611] 'applied index is now lower than readState.Index' (duration: 283.769344ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:46.395579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.0089Z","time spent":"386.637312ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:46.395695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.553633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4s22g\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-14T10:47:46.395737Z","caller":"traceutil/trace.go:171","msg":"trace[1246325863] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4s22g; range_end:; response_count:1; response_revision:632; }","duration":"357.605014ms","start":"2026-04-14T10:47:46.038121Z","end":"2026-04-14T10:47:46.395726Z","steps":["trace[1246325863] 'agreement among raft nodes before linearized reading' (duration: 357.460711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.395769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.038115Z","time spent":"357.648025ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-4s22g\" "} {"level":"warn","ts":"2026-04-14T10:47:46.81899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.917347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:46.819093Z","caller":"traceutil/trace.go:171","msg":"trace[1481245572] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"423.518656ms","start":"2026-04-14T10:47:46.395556Z","end":"2026-04-14T10:47:46.819075Z","steps":["trace[1481245572] 'read index received' (duration: 267.538898ms)","trace[1481245572] 'applied index is now lower than readState.Index' (duration: 155.978798ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:46.81912Z","caller":"traceutil/trace.go:171","msg":"trace[176633627] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"639.309835ms","start":"2026-04-14T10:47:46.179776Z","end":"2026-04-14T10:47:46.819086Z","steps":["trace[176633627] 'process raft request' (duration: 483.226145ms)","trace[176633627] 'compare' (duration: 155.796245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:46.819282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.687075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:46.819296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.179751Z","time spent":"639.460758ms","remote":"127.0.0.1:37556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:46.819317Z","caller":"traceutil/trace.go:171","msg":"trace[1605014487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"610.766647ms","start":"2026-04-14T10:47:46.208542Z","end":"2026-04-14T10:47:46.819308Z","steps":["trace[1605014487] 'agreement among raft nodes before linearized reading' (duration: 610.593223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.819355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.208524Z","time spent":"610.823507ms","remote":"127.0.0.1:37368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:47:46.857879Z","caller":"traceutil/trace.go:171","msg":"trace[2091764216] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"448.370402ms","start":"2026-04-14T10:47:46.409459Z","end":"2026-04-14T10:47:46.85783Z","steps":["trace[2091764216] 'process raft request' (duration: 447.874072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:46.858014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.409426Z","time spent":"448.516284ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:47.688831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.354043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:47.688952Z","caller":"traceutil/trace.go:171","msg":"trace[2006515161] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"480.177995ms","start":"2026-04-14T10:47:47.208751Z","end":"2026-04-14T10:47:47.688929Z","steps":["trace[2006515161] 'read index received' (duration: 54.562077ms)","trace[2006515161] 'applied index is now lower than readState.Index' (duration: 425.613868ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:47.689061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.307837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:47.689093Z","caller":"traceutil/trace.go:171","msg":"trace[492265949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"480.370759ms","start":"2026-04-14T10:47:47.20871Z","end":"2026-04-14T10:47:47.689081Z","steps":["trace[492265949] 'agreement among raft nodes before linearized reading' (duration: 480.277867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:47.689124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:47.208688Z","time spent":"480.4287ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:47:47.689139Z","caller":"traceutil/trace.go:171","msg":"trace[939119054] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"815.920043ms","start":"2026-04-14T10:47:46.873153Z","end":"2026-04-14T10:47:47.689073Z","steps":["trace[939119054] 'process raft request' (duration: 390.229723ms)","trace[939119054] 'compare' (duration: 424.950835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:47.68927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:46.873134Z","time spent":"816.073766ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:48.256432Z","caller":"traceutil/trace.go:171","msg":"trace[1531052561] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"562.576266ms","start":"2026-04-14T10:47:47.693829Z","end":"2026-04-14T10:47:48.256405Z","steps":["trace[1531052561] 'process raft request' (duration: 562.325561ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:48.25645Z","caller":"traceutil/trace.go:171","msg":"trace[1250858683] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"562.583087ms","start":"2026-04-14T10:47:47.693846Z","end":"2026-04-14T10:47:48.256429Z","steps":["trace[1250858683] 'process raft request' (duration: 562.458614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:48.256568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:47.693804Z","time spent":"562.696019ms","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:48.256571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:47.693826Z","time spent":"562.681468ms","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:48.261519Z","caller":"traceutil/trace.go:171","msg":"trace[1395970378] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"565.706459ms","start":"2026-04-14T10:47:47.695788Z","end":"2026-04-14T10:47:48.261495Z","steps":["trace[1395970378] 'process raft request' (duration: 565.656378ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:48.261569Z","caller":"traceutil/trace.go:171","msg":"trace[1390388670] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"566.058316ms","start":"2026-04-14T10:47:47.69549Z","end":"2026-04-14T10:47:48.261548Z","steps":["trace[1390388670] 'process raft request' (duration: 565.74155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:48.261697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:47.695772Z","time spent":"565.821471ms","remote":"127.0.0.1:37556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:48.261749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:47.695468Z","time spent":"566.192608ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:48:05.530619Z","caller":"traceutil/trace.go:171","msg":"trace[229577805] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"311.969654ms","start":"2026-04-14T10:48:05.218624Z","end":"2026-04-14T10:48:05.530593Z","steps":["trace[229577805] 'read index received' (duration: 311.823491ms)","trace[229577805] 'applied index is now lower than readState.Index' (duration: 144.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:05.530698Z","caller":"traceutil/trace.go:171","msg":"trace[1263470618] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"360.317107ms","start":"2026-04-14T10:48:05.170342Z","end":"2026-04-14T10:48:05.530659Z","steps":["trace[1263470618] 'process raft request' (duration: 360.093293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:05.530847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.165697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:48:05.530887Z","caller":"traceutil/trace.go:171","msg":"trace[773603698] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:801; }","duration":"312.242869ms","start":"2026-04-14T10:48:05.218633Z","end":"2026-04-14T10:48:05.530876Z","steps":["trace[773603698] 'agreement among raft nodes before linearized reading' (duration: 312.138607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:05.530893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:05.17033Z","time spent":"360.46073ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:48:05.530924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:05.218627Z","time spent":"312.28786ms","remote":"127.0.0.1:37732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:48:05.530907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.891622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:05.530984Z","caller":"traceutil/trace.go:171","msg":"trace[240618792] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:801; }","duration":"194.043305ms","start":"2026-04-14T10:48:05.336926Z","end":"2026-04-14T10:48:05.53097Z","steps":["trace[240618792] 'agreement among raft nodes before linearized reading' (duration: 193.877992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:05.53087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.196618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:05.531441Z","caller":"traceutil/trace.go:171","msg":"trace[498760990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"312.816411ms","start":"2026-04-14T10:48:05.218595Z","end":"2026-04-14T10:48:05.531411Z","steps":["trace[498760990] 'agreement among raft nodes before linearized reading' (duration: 312.138147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:05.531527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:05.218581Z","time spent":"312.930344ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:48:40.820266Z","caller":"traceutil/trace.go:171","msg":"trace[1376980319] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"521.611459ms","start":"2026-04-14T10:48:40.29863Z","end":"2026-04-14T10:48:40.820241Z","steps":["trace[1376980319] 'process raft request' (duration: 521.25263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:40.820427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:40.298612Z","time spent":"521.728411ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:48:40.821129Z","caller":"traceutil/trace.go:171","msg":"trace[1380700795] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1050; }","duration":"332.004946ms","start":"2026-04-14T10:48:40.489103Z","end":"2026-04-14T10:48:40.821108Z","steps":["trace[1380700795] 'read index received' (duration: 331.996926ms)","trace[1380700795] 'applied index is now lower than readState.Index' (duration: 7.01µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:40.821353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.902765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T10:48:40.821405Z","caller":"traceutil/trace.go:171","msg":"trace[38772260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"137.953886ms","start":"2026-04-14T10:48:40.683442Z","end":"2026-04-14T10:48:40.821396Z","steps":["trace[38772260] 'agreement among raft nodes before linearized reading' (duration: 137.818473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:40.821386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.294732ms","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-14T10:48:40.821454Z","caller":"traceutil/trace.go:171","msg":"trace[536239836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"154.385124ms","start":"2026-04-14T10:48:40.667055Z","end":"2026-04-14T10:48:40.82144Z","steps":["trace[536239836] 'agreement among raft nodes before linearized reading' (duration: 154.2182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:40.821471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.050148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-14T10:48:40.821244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.111199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:40.821547Z","caller":"traceutil/trace.go:171","msg":"trace[663374310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"132.195121ms","start":"2026-04-14T10:48:40.689335Z","end":"2026-04-14T10:48:40.82153Z","steps":["trace[663374310] 'agreement among raft nodes before linearized reading' (duration: 131.967136ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:40.82158Z","caller":"traceutil/trace.go:171","msg":"trace[205627665] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1013; }","duration":"332.505387ms","start":"2026-04-14T10:48:40.489065Z","end":"2026-04-14T10:48:40.821571Z","steps":["trace[205627665] 'agreement among raft nodes before linearized reading' (duration: 332.112578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:40.821614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:40.489056Z","time spent":"332.547048ms","remote":"127.0.0.1:37556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:48:40.821825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.746133ms","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-14T10:48:40.82186Z","caller":"traceutil/trace.go:171","msg":"trace[1340023234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"155.893288ms","start":"2026-04-14T10:48:40.665957Z","end":"2026-04-14T10:48:40.82185Z","steps":["trace[1340023234] 'agreement among raft nodes before linearized reading' (duration: 155.295715ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:41.370863Z","caller":"traceutil/trace.go:171","msg":"trace[1478285695] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"545.17024ms","start":"2026-04-14T10:48:40.825666Z","end":"2026-04-14T10:48:41.370836Z","steps":["trace[1478285695] 'process raft request' (duration: 545.080028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:41.371011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:40.825644Z","time spent":"545.296094ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:48:41.371079Z","caller":"traceutil/trace.go:171","msg":"trace[1396443159] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"544.928376ms","start":"2026-04-14T10:48:40.826125Z","end":"2026-04-14T10:48:41.371053Z","steps":["trace[1396443159] 'process raft request' (duration: 544.67829ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:41.371191Z","caller":"traceutil/trace.go:171","msg":"trace[1680222232] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"546.199663ms","start":"2026-04-14T10:48:40.824978Z","end":"2026-04-14T10:48:41.371178Z","steps":["trace[1680222232] 'process raft request' (duration: 493.764391ms)","trace[1680222232] 'compare' (duration: 51.702237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:41.371223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:40.826115Z","time spent":"545.028548ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:48:41.371245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:40.824962Z","time spent":"546.255115ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:48:41.371438Z","caller":"traceutil/trace.go:171","msg":"trace[1642070452] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1050; }","duration":"164.735969ms","start":"2026-04-14T10:48:41.206661Z","end":"2026-04-14T10:48:41.371397Z","steps":["trace[1642070452] 'read index received' (duration: 112.146904ms)","trace[1642070452] 'applied index is now lower than readState.Index' (duration: 52.587965ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:41.371544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.882854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:41.371574Z","caller":"traceutil/trace.go:171","msg":"trace[134515784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"164.980096ms","start":"2026-04-14T10:48:41.206585Z","end":"2026-04-14T10:48:41.371565Z","steps":["trace[134515784] 'agreement among raft nodes before linearized reading' (duration: 164.914874ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:41.371633Z","caller":"traceutil/trace.go:171","msg":"trace[1797196851] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"544.034896ms","start":"2026-04-14T10:48:40.827572Z","end":"2026-04-14T10:48:41.371607Z","steps":["trace[1797196851] 'process raft request' (duration: 543.411453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:41.3718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:40.827564Z","time spent":"544.133528ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:05.275618Z","caller":"traceutil/trace.go:171","msg":"trace[1337962450] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"361.876237ms","start":"2026-04-14T10:49:04.91372Z","end":"2026-04-14T10:49:05.275596Z","steps":["trace[1337962450] 'process raft request' (duration: 361.762414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:05.275773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:04.913616Z","time spent":"362.089081ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:05.637335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.221451ms","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-14T10:49:05.637429Z","caller":"traceutil/trace.go:171","msg":"trace[1703466963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"104.366176ms","start":"2026-04-14T10:49:05.533038Z","end":"2026-04-14T10:49:05.637404Z","steps":["trace[1703466963] 'range keys from in-memory index tree' (duration: 104.072928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:05.637499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.954298ms","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-14T10:49:05.637609Z","caller":"traceutil/trace.go:171","msg":"trace[1077223428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"105.10033ms","start":"2026-04-14T10:49:05.532487Z","end":"2026-04-14T10:49:05.637588Z","steps":["trace[1077223428] 'range keys from in-memory index tree' (duration: 104.788394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:05.63774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.711137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-14T10:49:05.637709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.00785ms","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-14T10:49:05.637872Z","caller":"traceutil/trace.go:171","msg":"trace[1564519467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"123.907171ms","start":"2026-04-14T10:49:05.513941Z","end":"2026-04-14T10:49:05.637849Z","steps":["trace[1564519467] 'range keys from in-memory index tree' (duration: 123.192025ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:05.637878Z","caller":"traceutil/trace.go:171","msg":"trace[1726703054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"134.241785ms","start":"2026-04-14T10:49:05.50362Z","end":"2026-04-14T10:49:05.637862Z","steps":["trace[1726703054] 'range keys from in-memory index tree' (duration: 133.53915ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:05.795642Z","caller":"traceutil/trace.go:171","msg":"trace[441038355] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"152.743589ms","start":"2026-04-14T10:49:05.642873Z","end":"2026-04-14T10:49:05.795616Z","steps":["trace[441038355] 'process raft request' (duration: 152.653727ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:05.795689Z","caller":"traceutil/trace.go:171","msg":"trace[42421493] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"152.923503ms","start":"2026-04-14T10:49:05.642736Z","end":"2026-04-14T10:49:05.795659Z","steps":["trace[42421493] 'process raft request' (duration: 152.605366ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:05.796046Z","caller":"traceutil/trace.go:171","msg":"trace[915053960] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"152.446162ms","start":"2026-04-14T10:49:05.643583Z","end":"2026-04-14T10:49:05.796029Z","steps":["trace[915053960] 'process raft request' (duration: 151.998782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:08.333276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.053945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:08.333379Z","caller":"traceutil/trace.go:171","msg":"trace[1375670361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"125.25138ms","start":"2026-04-14T10:49:08.208107Z","end":"2026-04-14T10:49:08.333359Z","steps":["trace[1375670361] 'range keys from in-memory index tree' (duration: 124.953604ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:09.518921Z","caller":"traceutil/trace.go:171","msg":"trace[633689149] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"154.398745ms","start":"2026-04-14T10:49:09.364497Z","end":"2026-04-14T10:49:09.518896Z","steps":["trace[633689149] 'process raft request' (duration: 154.241472ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:23.968611Z","caller":"traceutil/trace.go:171","msg":"trace[617978697] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"218.337835ms","start":"2026-04-14T10:49:23.750248Z","end":"2026-04-14T10:49:23.968586Z","steps":["trace[617978697] 'read index received' (duration: 218.150411ms)","trace[617978697] 'applied index is now lower than readState.Index' (duration: 185.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:23.968763Z","caller":"traceutil/trace.go:171","msg":"trace[1377358089] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"379.805341ms","start":"2026-04-14T10:49:23.588919Z","end":"2026-04-14T10:49:23.968725Z","steps":["trace[1377358089] 'process raft request' (duration: 379.503375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:23.968919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.657232ms","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-14T10:49:23.968964Z","caller":"traceutil/trace.go:171","msg":"trace[562405573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"218.748494ms","start":"2026-04-14T10:49:23.750204Z","end":"2026-04-14T10:49:23.968952Z","steps":["trace[562405573] 'agreement among raft nodes before linearized reading' (duration: 218.56015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:23.969686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:23.588889Z","time spent":"379.992514ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:36.858908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.828057ms","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-14T10:49:36.858987Z","caller":"traceutil/trace.go:171","msg":"trace[1824576129] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1247; }","duration":"155.936279ms","start":"2026-04-14T10:49:36.703034Z","end":"2026-04-14T10:49:36.858971Z","steps":["trace[1824576129] 'count revisions from in-memory index tree' (duration: 155.730536ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:38.978374Z","caller":"traceutil/trace.go:171","msg":"trace[1187285554] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"452.753579ms","start":"2026-04-14T10:49:38.5256Z","end":"2026-04-14T10:49:38.978354Z","steps":["trace[1187285554] 'process raft request' (duration: 452.637627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:38.978516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:38.525582Z","time spent":"452.855051ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:39.072844Z","caller":"traceutil/trace.go:171","msg":"trace[2077336468] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"461.329271ms","start":"2026-04-14T10:49:38.611491Z","end":"2026-04-14T10:49:39.072821Z","steps":["trace[2077336468] 'read index received' (duration: 366.680455ms)","trace[2077336468] 'applied index is now lower than readState.Index' (duration: 94.647636ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:39.072912Z","caller":"traceutil/trace.go:171","msg":"trace[1491538962] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"475.01017ms","start":"2026-04-14T10:49:38.597865Z","end":"2026-04-14T10:49:39.072875Z","steps":["trace[1491538962] 'process raft request' (duration: 435.749912ms)","trace[1491538962] 'compare' (duration: 39.086684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:39.073066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.549015ms","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-14T10:49:39.073076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:38.597833Z","time spent":"475.147762ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:39.073103Z","caller":"traceutil/trace.go:171","msg":"trace[736647241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"461.637747ms","start":"2026-04-14T10:49:38.611457Z","end":"2026-04-14T10:49:39.073095Z","steps":["trace[736647241] 'agreement among raft nodes before linearized reading' (duration: 461.485734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:39.073134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:38.611444Z","time spent":"461.683928ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T10:49:40.198952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.265676ms","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-14T10:49:40.199131Z","caller":"traceutil/trace.go:171","msg":"trace[297254466] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1274; }","duration":"192.46529ms","start":"2026-04-14T10:49:40.00664Z","end":"2026-04-14T10:49:40.199106Z","steps":["trace[297254466] 'range keys from in-memory index tree' (duration: 192.243835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.199733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.069636ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-14T10:49:40.199816Z","caller":"traceutil/trace.go:171","msg":"trace[490527057] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"582.356484ms","start":"2026-04-14T10:49:39.617447Z","end":"2026-04-14T10:49:40.199803Z","steps":["trace[490527057] 'read index received' (duration: 31.151µs)","trace[490527057] 'applied index is now lower than readState.Index' (duration: 582.324193ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:40.199886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:39.110675Z","time spent":"1.089207595s","remote":"127.0.0.1:37390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-14T10:49:40.200191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.735492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:49:40.200238Z","caller":"traceutil/trace.go:171","msg":"trace[95658273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1274; }","duration":"582.813893ms","start":"2026-04-14T10:49:39.617409Z","end":"2026-04-14T10:49:40.200223Z","steps":["trace[95658273] 'agreement among raft nodes before linearized reading' (duration: 582.68272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.200285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:39.617392Z","time spent":"582.878325ms","remote":"127.0.0.1:40790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:49:40.371236Z","caller":"traceutil/trace.go:171","msg":"trace[1052434256] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1326; }","duration":"163.06255ms","start":"2026-04-14T10:49:40.208144Z","end":"2026-04-14T10:49:40.371207Z","steps":["trace[1052434256] 'read index received' (duration: 23.163478ms)","trace[1052434256] 'applied index is now lower than readState.Index' (duration: 139.897552ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:40.371346Z","caller":"traceutil/trace.go:171","msg":"trace[1096949807] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"164.018639ms","start":"2026-04-14T10:49:40.207246Z","end":"2026-04-14T10:49:40.371265Z","steps":["trace[1096949807] 'process raft request' (duration: 98.351884ms)","trace[1096949807] 'compare' (duration: 65.402769ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:40.371646Z","caller":"traceutil/trace.go:171","msg":"trace[1546070300] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"163.405677ms","start":"2026-04-14T10:49:40.208227Z","end":"2026-04-14T10:49:40.371633Z","steps":["trace[1546070300] 'process raft request' (duration: 162.924966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.371899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.714424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:40.404368Z","caller":"traceutil/trace.go:171","msg":"trace[1903758120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"196.252306ms","start":"2026-04-14T10:49:40.208092Z","end":"2026-04-14T10:49:40.404344Z","steps":["trace[1903758120] 'agreement among raft nodes before linearized reading' (duration: 163.727944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.404686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.316395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:40.404717Z","caller":"traceutil/trace.go:171","msg":"trace[933771913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1278; }","duration":"157.382467ms","start":"2026-04-14T10:49:40.247326Z","end":"2026-04-14T10:49:40.404708Z","steps":["trace[933771913] 'agreement among raft nodes before linearized reading' (duration: 157.318785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.404785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.913622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-04-14T10:49:40.404921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.445551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-14T10:49:40.404945Z","caller":"traceutil/trace.go:171","msg":"trace[251940301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"188.482482ms","start":"2026-04-14T10:49:40.216456Z","end":"2026-04-14T10:49:40.404938Z","steps":["trace[251940301] 'agreement among raft nodes before linearized reading' (duration: 188.405171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.404705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.224659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-14T10:49:40.404819Z","caller":"traceutil/trace.go:171","msg":"trace[16319725] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1278; }","duration":"171.944543ms","start":"2026-04-14T10:49:40.232865Z","end":"2026-04-14T10:49:40.40481Z","steps":["trace[16319725] 'agreement among raft nodes before linearized reading' (duration: 171.862781ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:40.405075Z","caller":"traceutil/trace.go:171","msg":"trace[1581275456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1278; }","duration":"190.635298ms","start":"2026-04-14T10:49:40.214434Z","end":"2026-04-14T10:49:40.405069Z","steps":["trace[1581275456] 'agreement among raft nodes before linearized reading' (duration: 190.154098ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:40.557585Z","caller":"traceutil/trace.go:171","msg":"trace[321740319] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"148.984283ms","start":"2026-04-14T10:49:40.408326Z","end":"2026-04-14T10:49:40.55731Z","steps":["trace[321740319] 'process raft request' (duration: 123.773771ms)","trace[321740319] 'compare' (duration: 25.083289ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:41.240168Z","caller":"traceutil/trace.go:171","msg":"trace[509000997] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"126.782205ms","start":"2026-04-14T10:49:41.113356Z","end":"2026-04-14T10:49:41.240138Z","steps":["trace[509000997] 'process raft request' (duration: 126.515949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:41.900374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.320704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:41.900485Z","caller":"traceutil/trace.go:171","msg":"trace[1732600673] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"382.167711ms","start":"2026-04-14T10:49:41.5183Z","end":"2026-04-14T10:49:41.900467Z","steps":["trace[1732600673] 'read index received' (duration: 17.396937ms)","trace[1732600673] 'applied index is now lower than readState.Index' (duration: 364.768803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:41.900584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.271663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:41.900632Z","caller":"traceutil/trace.go:171","msg":"trace[1005445746] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1283; }","duration":"382.345023ms","start":"2026-04-14T10:49:41.518256Z","end":"2026-04-14T10:49:41.900601Z","steps":["trace[1005445746] 'agreement among raft nodes before linearized reading' (duration: 382.257121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:41.900668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:41.518234Z","time spent":"382.425266ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-14T10:49:41.900635Z","caller":"traceutil/trace.go:171","msg":"trace[106422063] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"460.299488ms","start":"2026-04-14T10:49:41.440291Z","end":"2026-04-14T10:49:41.90059Z","steps":["trace[106422063] 'process raft request' (duration: 95.455543ms)","trace[106422063] 'compare' (duration: 364.10106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:41.900804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:41.440262Z","time spent":"460.457662ms","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:49:42.568345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.827478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:42.568446Z","caller":"traceutil/trace.go:171","msg":"trace[278017146] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"663.567947ms","start":"2026-04-14T10:49:41.90486Z","end":"2026-04-14T10:49:42.568428Z","steps":["trace[278017146] 'process raft request' (duration: 543.581045ms)","trace[278017146] 'compare' (duration: 119.336387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:42.568499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:41.904835Z","time spent":"663.642069ms","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:49:42.820691Z","caller":"traceutil/trace.go:171","msg":"trace[36807521] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"247.540082ms","start":"2026-04-14T10:49:42.573126Z","end":"2026-04-14T10:49:42.820666Z","steps":["trace[36807521] 'process raft request' (duration: 247.493881ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.82074Z","caller":"traceutil/trace.go:171","msg":"trace[1374274977] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"613.216595ms","start":"2026-04-14T10:49:42.207476Z","end":"2026-04-14T10:49:42.820693Z","steps":["trace[1374274977] 'process raft request' (duration: 613.038411ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.820827Z","caller":"traceutil/trace.go:171","msg":"trace[595996285] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"915.558423ms","start":"2026-04-14T10:49:41.90523Z","end":"2026-04-14T10:49:42.820789Z","steps":["trace[595996285] 'process raft request' (duration: 860.602623ms)","trace[595996285] 'compare' (duration: 54.369718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:42.82096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:41.905207Z","time spent":"915.688605ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:49:42.820966Z","caller":"traceutil/trace.go:171","msg":"trace[1168195501] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1334; }","duration":"411.623103ms","start":"2026-04-14T10:49:42.409301Z","end":"2026-04-14T10:49:42.820924Z","steps":["trace[1168195501] 'read index received' (duration: 39.150726ms)","trace[1168195501] 'applied index is now lower than readState.Index' (duration: 372.470997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:42.820957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:42.207453Z","time spent":"613.378929ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:42.821213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.887618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T10:49:42.821259Z","caller":"traceutil/trace.go:171","msg":"trace[337908275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"411.954709ms","start":"2026-04-14T10:49:42.409294Z","end":"2026-04-14T10:49:42.821249Z","steps":["trace[337908275] 'agreement among raft nodes before linearized reading' (duration: 411.771286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:42.821298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:42.409236Z","time spent":"412.054171ms","remote":"127.0.0.1:37574","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-14T10:49:42.821448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.730334ms","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-14T10:49:42.821481Z","caller":"traceutil/trace.go:171","msg":"trace[1619993771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1287; }","duration":"411.808906ms","start":"2026-04-14T10:49:42.409664Z","end":"2026-04-14T10:49:42.821473Z","steps":["trace[1619993771] 'agreement among raft nodes before linearized reading' (duration: 411.723084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:42.821479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.707105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-14T10:49:42.821512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:42.409656Z","time spent":"411.850108ms","remote":"127.0.0.1:37574","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-14T10:49:42.821521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.557856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-14T10:49:42.821575Z","caller":"traceutil/trace.go:171","msg":"trace[5169046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"379.654168ms","start":"2026-04-14T10:49:42.441908Z","end":"2026-04-14T10:49:42.821562Z","steps":["trace[5169046] 'agreement among raft nodes before linearized reading' (duration: 379.489844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:42.821615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:42.441887Z","time spent":"379.721229ms","remote":"127.0.0.1:37574","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-14T10:49:42.821598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.34079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:49:42.821664Z","caller":"traceutil/trace.go:171","msg":"trace[11597585] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1287; }","duration":"396.459522ms","start":"2026-04-14T10:49:42.425191Z","end":"2026-04-14T10:49:42.821651Z","steps":["trace[11597585] 'agreement among raft nodes before linearized reading' (duration: 396.331369ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.821529Z","caller":"traceutil/trace.go:171","msg":"trace[666649115] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1287; }","duration":"375.791777ms","start":"2026-04-14T10:49:42.445723Z","end":"2026-04-14T10:49:42.821515Z","steps":["trace[666649115] 'agreement among raft nodes before linearized reading' (duration: 375.673374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:42.8217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:42.42517Z","time spent":"396.524364ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:49:42.821718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.612507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-14T10:49:42.821732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:42.445707Z","time spent":"376.014661ms","remote":"127.0.0.1:37732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:49:42.821767Z","caller":"traceutil/trace.go:171","msg":"trace[284484065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"259.695138ms","start":"2026-04-14T10:49:42.562062Z","end":"2026-04-14T10:49:42.821757Z","steps":["trace[284484065] 'agreement among raft nodes before linearized reading' (duration: 259.513784ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.958001Z","caller":"traceutil/trace.go:171","msg":"trace[1025109908] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"131.533664ms","start":"2026-04-14T10:49:42.826436Z","end":"2026-04-14T10:49:42.95797Z","steps":["trace[1025109908] 'read index received' (duration: 4.083336ms)","trace[1025109908] 'applied index is now lower than readState.Index' (duration: 127.448798ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:42.958039Z","caller":"traceutil/trace.go:171","msg":"trace[1837945667] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"131.641346ms","start":"2026-04-14T10:49:42.826369Z","end":"2026-04-14T10:49:42.95801Z","steps":["trace[1837945667] 'process raft request' (duration: 131.385721ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.958147Z","caller":"traceutil/trace.go:171","msg":"trace[2121613949] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"130.413161ms","start":"2026-04-14T10:49:42.827675Z","end":"2026-04-14T10:49:42.958088Z","steps":["trace[2121613949] 'process raft request' (duration: 130.245757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:42.958694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.213918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-14T10:49:42.958754Z","caller":"traceutil/trace.go:171","msg":"trace[958513511] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1290; }","duration":"132.329201ms","start":"2026-04-14T10:49:42.826411Z","end":"2026-04-14T10:49:42.95874Z","steps":["trace[958513511] 'agreement among raft nodes before linearized reading' (duration: 131.731479ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.964355Z","caller":"traceutil/trace.go:171","msg":"trace[41853429] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"131.115955ms","start":"2026-04-14T10:49:42.833221Z","end":"2026-04-14T10:49:42.964337Z","steps":["trace[41853429] 'process raft request' (duration: 131.063474ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:42.965673Z","caller":"traceutil/trace.go:171","msg":"trace[2136402382] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"133.383284ms","start":"2026-04-14T10:49:42.832269Z","end":"2026-04-14T10:49:42.965652Z","steps":["trace[2136402382] 'process raft request' (duration: 131.914942ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:44.318502Z","caller":"traceutil/trace.go:171","msg":"trace[76806843] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"251.730778ms","start":"2026-04-14T10:49:44.066711Z","end":"2026-04-14T10:49:44.318442Z","steps":["trace[76806843] 'process raft request' (duration: 251.561035ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:45.040005Z","caller":"traceutil/trace.go:171","msg":"trace[856310717] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"204.708318ms","start":"2026-04-14T10:49:44.835273Z","end":"2026-04-14T10:49:45.039982Z","steps":["trace[856310717] 'read index received' (duration: 204.514994ms)","trace[856310717] 'applied index is now lower than readState.Index' (duration: 192.434µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:45.040294Z","caller":"traceutil/trace.go:171","msg":"trace[1590434667] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"213.34693ms","start":"2026-04-14T10:49:44.826916Z","end":"2026-04-14T10:49:45.040263Z","steps":["trace[1590434667] 'process raft request' (duration: 212.936021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:45.040354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.071005ms","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-14T10:49:45.04043Z","caller":"traceutil/trace.go:171","msg":"trace[1339012416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1297; }","duration":"205.186818ms","start":"2026-04-14T10:49:44.835226Z","end":"2026-04-14T10:49:45.040413Z","steps":["trace[1339012416] 'agreement among raft nodes before linearized reading' (duration: 204.972393ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:45.243299Z","caller":"traceutil/trace.go:171","msg":"trace[499893348] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"198.811043ms","start":"2026-04-14T10:49:45.044461Z","end":"2026-04-14T10:49:45.243272Z","steps":["trace[499893348] 'process raft request' (duration: 99.141981ms)","trace[499893348] 'compare' (duration: 99.55041ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:45.246396Z","caller":"traceutil/trace.go:171","msg":"trace[1455984860] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"200.223364ms","start":"2026-04-14T10:49:45.046154Z","end":"2026-04-14T10:49:45.246377Z","steps":["trace[1455984860] 'process raft request' (duration: 199.887297ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:45.246552Z","caller":"traceutil/trace.go:171","msg":"trace[906575270] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"200.377627ms","start":"2026-04-14T10:49:45.046167Z","end":"2026-04-14T10:49:45.246545Z","steps":["trace[906575270] 'process raft request' (duration: 199.945418ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:45.246639Z","caller":"traceutil/trace.go:171","msg":"trace[1030938363] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"200.234144ms","start":"2026-04-14T10:49:45.046399Z","end":"2026-04-14T10:49:45.246633Z","steps":["trace[1030938363] 'process raft request' (duration: 199.737864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:53.708851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778049768068300709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:49:54.210061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2778049768068300709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:49:54.381315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.371814457s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T10:49:54.381756Z","caller":"traceutil/trace.go:171","msg":"trace[353276245] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.372356538s","start":"2026-04-14T10:49:53.009376Z","end":"2026-04-14T10:49:54.381733Z","steps":["trace[353276245] 'process raft request' (duration: 1.372223795s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.381902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.009349Z","time spent":"1.372477041s","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:54.390773Z","caller":"traceutil/trace.go:171","msg":"trace[352540732] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"1.182397661s","start":"2026-04-14T10:49:53.208357Z","end":"2026-04-14T10:49:54.390754Z","steps":["trace[352540732] 'read index received' (duration: 1.173394331s)","trace[352540732] 'applied index is now lower than readState.Index' (duration: 9.00255ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:54.390875Z","caller":"traceutil/trace.go:171","msg":"trace[284427326] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.324683432s","start":"2026-04-14T10:49:53.066168Z","end":"2026-04-14T10:49:54.390852Z","steps":["trace[284427326] 'process raft request' (duration: 1.318559373s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.391008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.066148Z","time spent":"1.324794475s","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:49:54.391031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114863547s","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-14T10:49:54.391015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.876111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-14T10:49:54.391057Z","caller":"traceutil/trace.go:171","msg":"trace[1364895911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"1.114959149s","start":"2026-04-14T10:49:53.276088Z","end":"2026-04-14T10:49:54.391048Z","steps":["trace[1364895911] 'agreement among raft nodes before linearized reading' (duration: 1.114847847s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:54.391074Z","caller":"traceutil/trace.go:171","msg":"trace[2091293094] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1361; }","duration":"897.962733ms","start":"2026-04-14T10:49:53.493102Z","end":"2026-04-14T10:49:54.391065Z","steps":["trace[2091293094] 'agreement among raft nodes before linearized reading' (duration: 897.84155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.39109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.276068Z","time spent":"1.1150117s","remote":"127.0.0.1:37574","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-14T10:49:54.391098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.493088Z","time spent":"898.003903ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-14T10:49:54.3911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.541746ms","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":"warn","ts":"2026-04-14T10:49:54.391182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.576263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:54.391175Z","caller":"traceutil/trace.go:171","msg":"trace[579661694] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1361; }","duration":"589.675858ms","start":"2026-04-14T10:49:53.801475Z","end":"2026-04-14T10:49:54.391151Z","steps":["trace[579661694] 'agreement among raft nodes before linearized reading' (duration: 589.501155ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:54.391203Z","caller":"traceutil/trace.go:171","msg":"trace[1583869749] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1361; }","duration":"749.631884ms","start":"2026-04-14T10:49:53.641565Z","end":"2026-04-14T10:49:54.391197Z","steps":["trace[1583869749] 'agreement among raft nodes before linearized reading' (duration: 749.595203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.391228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013855357s","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-14T10:49:54.39091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182546415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:49:54.391247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.641547Z","time spent":"749.694155ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:49:54.391234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.801463Z","time spent":"589.760391ms","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:49:54.391254Z","caller":"traceutil/trace.go:171","msg":"trace[513884354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.013948268s","start":"2026-04-14T10:49:53.377298Z","end":"2026-04-14T10:49:54.391247Z","steps":["trace[513884354] 'agreement among raft nodes before linearized reading' (duration: 1.013878077s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:54.391265Z","caller":"traceutil/trace.go:171","msg":"trace[1536300018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"1.183043654s","start":"2026-04-14T10:49:53.208209Z","end":"2026-04-14T10:49:54.391253Z","steps":["trace[1536300018] 'agreement among raft nodes before linearized reading' (duration: 1.182658086s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.391309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.208087Z","time spent":"1.183212008s","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T10:49:54.391328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102328382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T10:49:54.391348Z","caller":"traceutil/trace.go:171","msg":"trace[482676554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.102346402s","start":"2026-04-14T10:49:53.288995Z","end":"2026-04-14T10:49:54.391341Z","steps":["trace[482676554] 'agreement among raft nodes before linearized reading' (duration: 1.102296921s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.391364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.288923Z","time spent":"1.102437135s","remote":"127.0.0.1:37574","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-14T10:49:54.3915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103271332s","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-14T10:49:54.391518Z","caller":"traceutil/trace.go:171","msg":"trace[702522598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"1.103314464s","start":"2026-04-14T10:49:53.288198Z","end":"2026-04-14T10:49:54.391513Z","steps":["trace[702522598] 'agreement among raft nodes before linearized reading' (duration: 1.103260843s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.391534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.288186Z","time spent":"1.103344224s","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T10:49:54.391558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103341965s","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-14T10:49:54.391588Z","caller":"traceutil/trace.go:171","msg":"trace[996296171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.103399315s","start":"2026-04-14T10:49:53.288181Z","end":"2026-04-14T10:49:54.39158Z","steps":["trace[996296171] 'agreement among raft nodes before linearized reading' (duration: 1.103324444s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:54.391607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.28817Z","time spent":"1.103433426s","remote":"127.0.0.1:37574","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-14T10:49:54.391281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:53.377274Z","time spent":"1.01400204s","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T10:50:00.635463Z","caller":"traceutil/trace.go:171","msg":"trace[1875356791] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"210.977241ms","start":"2026-04-14T10:50:00.424458Z","end":"2026-04-14T10:50:00.635435Z","steps":["trace[1875356791] 'read index received' (duration: 210.769386ms)","trace[1875356791] 'applied index is now lower than readState.Index' (duration: 206.654µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:00.635528Z","caller":"traceutil/trace.go:171","msg":"trace[867754085] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"218.282955ms","start":"2026-04-14T10:50:00.417213Z","end":"2026-04-14T10:50:00.635496Z","steps":["trace[867754085] 'process raft request' (duration: 218.0745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:00.635715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.237856ms","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-14T10:50:00.635768Z","caller":"traceutil/trace.go:171","msg":"trace[369023416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1390; }","duration":"211.344138ms","start":"2026-04-14T10:50:00.42441Z","end":"2026-04-14T10:50:00.635754Z","steps":["trace[369023416] 'agreement among raft nodes before linearized reading' (duration: 211.168564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:00.63578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.241716ms","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-14T10:50:00.635823Z","caller":"traceutil/trace.go:171","msg":"trace[2015697405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1390; }","duration":"203.296557ms","start":"2026-04-14T10:50:00.432515Z","end":"2026-04-14T10:50:00.635812Z","steps":["trace[2015697405] 'agreement among raft nodes before linearized reading' (duration: 203.168245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:00.635855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.255278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-14T10:50:00.635895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.887527ms","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-14T10:50:00.635955Z","caller":"traceutil/trace.go:171","msg":"trace[325120137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1390; }","duration":"200.964538ms","start":"2026-04-14T10:50:00.434977Z","end":"2026-04-14T10:50:00.635941Z","steps":["trace[325120137] 'agreement among raft nodes before linearized reading' (duration: 200.811445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:00.635963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.967202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-14T10:50:00.635998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.686546ms","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-14T10:50:00.636068Z","caller":"traceutil/trace.go:171","msg":"trace[642564920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1390; }","duration":"203.775877ms","start":"2026-04-14T10:50:00.432274Z","end":"2026-04-14T10:50:00.63605Z","steps":["trace[642564920] 'agreement among raft nodes before linearized reading' (duration: 203.567213ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.636019Z","caller":"traceutil/trace.go:171","msg":"trace[1841806070] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1390; }","duration":"204.032324ms","start":"2026-04-14T10:50:00.431975Z","end":"2026-04-14T10:50:00.636007Z","steps":["trace[1841806070] 'agreement among raft nodes before linearized reading' (duration: 203.910192ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.635955Z","caller":"traceutil/trace.go:171","msg":"trace[874628429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1390; }","duration":"204.372841ms","start":"2026-04-14T10:50:00.431559Z","end":"2026-04-14T10:50:00.635932Z","steps":["trace[874628429] 'agreement among raft nodes before linearized reading' (duration: 204.089165ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:52:50.777318Z","caller":"traceutil/trace.go:171","msg":"trace[864308365] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"103.738258ms","start":"2026-04-14T10:52:50.673542Z","end":"2026-04-14T10:52:50.77728Z","steps":["trace[864308365] 'process raft request' (duration: 103.515273ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:53:02.250555Z","caller":"traceutil/trace.go:171","msg":"trace[508612393] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2339; }","duration":"184.982939ms","start":"2026-04-14T10:53:02.065551Z","end":"2026-04-14T10:53:02.250534Z","steps":["trace[508612393] 'read index received' (duration: 183.165028ms)","trace[508612393] 'applied index is now lower than readState.Index' (duration: 1.816991ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:53:02.250759Z","caller":"traceutil/trace.go:171","msg":"trace[44868942] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"185.734995ms","start":"2026-04-14T10:53:02.065013Z","end":"2026-04-14T10:53:02.250748Z","steps":["trace[44868942] 'process raft request' (duration: 183.72152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:53:02.251115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.675888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:53:02.251158Z","caller":"traceutil/trace.go:171","msg":"trace[1701806953] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2248; }","duration":"168.76604ms","start":"2026-04-14T10:53:02.08238Z","end":"2026-04-14T10:53:02.251146Z","steps":["trace[1701806953] 'agreement among raft nodes before linearized reading' (duration: 168.684378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:53:02.251188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.606672ms","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-14T10:53:02.25128Z","caller":"traceutil/trace.go:171","msg":"trace[499018645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2248; }","duration":"185.728725ms","start":"2026-04-14T10:53:02.065528Z","end":"2026-04-14T10:53:02.251257Z","steps":["trace[499018645] 'agreement among raft nodes before linearized reading' (duration: 185.47822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:53:02.251649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.013231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-14T10:53:02.251707Z","caller":"traceutil/trace.go:171","msg":"trace[1175932992] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2248; }","duration":"186.090724ms","start":"2026-04-14T10:53:02.065588Z","end":"2026-04-14T10:53:02.251679Z","steps":["trace[1175932992] 'agreement among raft nodes before linearized reading' (duration: 185.362748ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:53:08.130569Z","caller":"traceutil/trace.go:171","msg":"trace[1418477110] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"110.049429ms","start":"2026-04-14T10:53:08.020488Z","end":"2026-04-14T10:53:08.130538Z","steps":["trace[1418477110] 'process raft request' (duration: 91.963018ms)","trace[1418477110] 'compare' (duration: 15.102075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:53:08.380454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.463537ms","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-14T10:53:08.380534Z","caller":"traceutil/trace.go:171","msg":"trace[252031377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2281; }","duration":"100.575829ms","start":"2026-04-14T10:53:08.279938Z","end":"2026-04-14T10:53:08.380514Z","steps":["trace[252031377] 'range keys from in-memory index tree' (duration: 100.336944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:53:27.086572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.282485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/\" range_end:\"/registry/jobs/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:53:27.086667Z","caller":"traceutil/trace.go:171","msg":"trace[721767349] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/; range_end:/registry/jobs/envoy-gateway-system0; response_count:0; response_revision:2374; }","duration":"139.43176ms","start":"2026-04-14T10:53:26.947214Z","end":"2026-04-14T10:53:27.086646Z","steps":["trace[721767349] 'range keys from in-memory index tree' (duration: 139.202464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:53:27.086684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.759096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-14T10:53:27.086735Z","caller":"traceutil/trace.go:171","msg":"trace[1744290062] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2374; }","duration":"111.844299ms","start":"2026-04-14T10:53:26.974879Z","end":"2026-04-14T10:53:27.086724Z","steps":["trace[1744290062] 'range keys from in-memory index tree' (duration: 111.656343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:53:27.086933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.802725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:53:27.086951Z","caller":"traceutil/trace.go:171","msg":"trace[1714345021] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:2374; }","duration":"119.850476ms","start":"2026-04-14T10:53:26.967093Z","end":"2026-04-14T10:53:27.086943Z","steps":["trace[1714345021] 'range keys from in-memory index tree' (duration: 119.740494ms)"],"step_count":1}