{"level":"warn","ts":"2026-04-23T16:58:04.39122Z","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-23T16:58:04.3913Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.43: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.43:2380","--initial-cluster=instance=https://199.19.213.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.43: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-23T16:58:04.391369Z","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-23T16:58:04.39138Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-04-23T16:58:04.391399Z","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-23T16:58:04.394172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"]} {"level":"info","ts":"2026-04-23T16:58:04.394311Z","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.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.43: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-23T16:58:04.51796Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"123.351175ms"} {"level":"info","ts":"2026-04-23T16:58:05.051146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a8d0274548ffaee4","cluster-id":"858b677d1d9175b1"} {"level":"info","ts":"2026-04-23T16:58:05.051263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T16:58:05.051292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 0"} {"level":"info","ts":"2026-04-23T16:58:05.051313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a8d0274548ffaee4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T16:58:05.051327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 1"} {"level":"info","ts":"2026-04-23T16:58:05.051386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"warn","ts":"2026-04-23T16:58:06.447925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T16:58:06.490998Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T16:58:06.492293Z","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-23T16:58:06.494637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a8d0274548ffaee4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T16:58:06.494823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a8d0274548ffaee4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T16:58:06.494943Z","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-23T16:58:06.495028Z","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-23T16:58:06.49504Z","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-23T16:58:06.496986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"info","ts":"2026-04-23T16:58:06.497151Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","added-peer-id":"a8d0274548ffaee4","added-peer-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-04-23T16:58:06.498368Z","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-23T16:58:06.498436Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-04-23T16:58:06.498465Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-04-23T16:58:06.498631Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a8d0274548ffaee4","initial-advertise-peer-urls":["https://199.19.213.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T16:58:06.498674Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T16:58:06.579187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T16:58:06.579261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T16:58:06.579309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgPreVoteResp from a8d0274548ffaee4 at term 1"} {"level":"info","ts":"2026-04-23T16:58:06.579332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became candidate at term 2"} {"level":"info","ts":"2026-04-23T16:58:06.579343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgVoteResp from a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-04-23T16:58:06.579361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became leader at term 2"} {"level":"info","ts":"2026-04-23T16:58:06.579376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a8d0274548ffaee4 elected leader a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-04-23T16:58:06.580287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:58:06.580945Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a8d0274548ffaee4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.43:2379]}","request-path":"/0/members/a8d0274548ffaee4/attributes","cluster-id":"858b677d1d9175b1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T16:58:06.580978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:58:06.581016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:58:06.581197Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T16:58:06.581268Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T16:58:06.581661Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:58:06.58182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:58:06.58187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:58:06.585492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T16:58:06.586227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.43:2379"} {"level":"warn","ts":"2026-04-23T16:58:08.467166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.183554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T16:58:08.467291Z","caller":"traceutil/trace.go:171","msg":"trace[929799462] linearizableReadLoop","detail":"{readStateIndex:98; appliedIndex:97; }","duration":"346.095597ms","start":"2026-04-23T16:58:08.121179Z","end":"2026-04-23T16:58:08.467275Z","steps":["trace[929799462] 'read index received' (duration: 3.271173ms)","trace[929799462] 'applied index is now lower than readState.Index' (duration: 342.822914ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:08.467352Z","caller":"traceutil/trace.go:171","msg":"trace[1568261352] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"400.901605ms","start":"2026-04-23T16:58:08.066424Z","end":"2026-04-23T16:58:08.467325Z","steps":["trace[1568261352] 'process raft request' (duration: 58.017889ms)","trace[1568261352] 'compare' (duration: 342.05257ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:08.467407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.229032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:58:08.467436Z","caller":"traceutil/trace.go:171","msg":"trace[566752847] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:93; }","duration":"346.302524ms","start":"2026-04-23T16:58:08.121125Z","end":"2026-04-23T16:58:08.467427Z","steps":["trace[566752847] 'agreement among raft nodes before linearized reading' (duration: 346.208401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:08.467475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:08.121079Z","time spent":"346.386827ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-23T16:58:08.467476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:08.06641Z","time spent":"401.024668ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:08.87589Z","caller":"traceutil/trace.go:171","msg":"trace[2094580560] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"222.944318ms","start":"2026-04-23T16:58:08.652925Z","end":"2026-04-23T16:58:08.875869Z","steps":["trace[2094580560] 'read index received' (duration: 203.609641ms)","trace[2094580560] 'applied index is now lower than readState.Index' (duration: 19.333727ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:08.875924Z","caller":"traceutil/trace.go:171","msg":"trace[1245179537] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"315.306363ms","start":"2026-04-23T16:58:08.56059Z","end":"2026-04-23T16:58:08.875896Z","steps":["trace[1245179537] 'process raft request' (duration: 296.051789ms)","trace[1245179537] 'compare' (duration: 19.119149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:08.876052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.365285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-23T16:58:08.876054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.746465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-23T16:58:08.876054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.113793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:58:08.876088Z","caller":"traceutil/trace.go:171","msg":"trace[188001461] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:99; }","duration":"136.442527ms","start":"2026-04-23T16:58:08.739636Z","end":"2026-04-23T16:58:08.876079Z","steps":["trace[188001461] 'agreement among raft nodes before linearized reading' (duration: 136.347574ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:08.876101Z","caller":"traceutil/trace.go:171","msg":"trace[351532203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:99; }","duration":"120.799246ms","start":"2026-04-23T16:58:08.755287Z","end":"2026-04-23T16:58:08.876086Z","steps":["trace[351532203] 'agreement among raft nodes before linearized reading' (duration: 120.721494ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:08.876118Z","caller":"traceutil/trace.go:171","msg":"trace[298102400] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:99; }","duration":"223.227887ms","start":"2026-04-23T16:58:08.652875Z","end":"2026-04-23T16:58:08.876102Z","steps":["trace[298102400] 'agreement among raft nodes before linearized reading' (duration: 223.085413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:08.876321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:08.56057Z","time spent":"315.409546ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:58:08.876443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.277811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:58:08.876579Z","caller":"traceutil/trace.go:171","msg":"trace[1357803401] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:99; }","duration":"136.460707ms","start":"2026-04-23T16:58:08.740094Z","end":"2026-04-23T16:58:08.876555Z","steps":["trace[1357803401] 'agreement among raft nodes before linearized reading' (duration: 136.018943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:09.018366Z","caller":"traceutil/trace.go:171","msg":"trace[1510385200] linearizableReadLoop","detail":"{readStateIndex:106; appliedIndex:104; }","duration":"138.534928ms","start":"2026-04-23T16:58:08.879812Z","end":"2026-04-23T16:58:09.018347Z","steps":["trace[1510385200] 'read index received' (duration: 128.94318ms)","trace[1510385200] 'applied index is now lower than readState.Index' (duration: 9.590868ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:09.018497Z","caller":"traceutil/trace.go:171","msg":"trace[1733017723] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"139.648872ms","start":"2026-04-23T16:58:08.878814Z","end":"2026-04-23T16:58:09.018463Z","steps":["trace[1733017723] 'process raft request' (duration: 129.864958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:09.018583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.731103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T16:58:09.018654Z","caller":"traceutil/trace.go:171","msg":"trace[1261578549] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:102; }","duration":"138.863178ms","start":"2026-04-23T16:58:08.879781Z","end":"2026-04-23T16:58:09.018644Z","steps":["trace[1261578549] 'agreement among raft nodes before linearized reading' (duration: 138.706973ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:09.018477Z","caller":"traceutil/trace.go:171","msg":"trace[45717354] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"139.018223ms","start":"2026-04-23T16:58:08.879393Z","end":"2026-04-23T16:58:09.018411Z","steps":["trace[45717354] 'process raft request' (duration: 138.894529ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:09.018616Z","caller":"traceutil/trace.go:171","msg":"trace[370384955] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"138.203098ms","start":"2026-04-23T16:58:08.88037Z","end":"2026-04-23T16:58:09.018573Z","steps":["trace[370384955] 'process raft request' (duration: 137.95461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:09.437078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.484941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T16:58:09.437385Z","caller":"traceutil/trace.go:171","msg":"trace[126044034] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"165.796415ms","start":"2026-04-23T16:58:09.271566Z","end":"2026-04-23T16:58:09.437362Z","steps":["trace[126044034] 'process raft request' (duration: 165.709692ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:09.437397Z","caller":"traceutil/trace.go:171","msg":"trace[683075572] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"167.641412ms","start":"2026-04-23T16:58:09.269721Z","end":"2026-04-23T16:58:09.437362Z","steps":["trace[683075572] 'process raft request' (duration: 34.771189ms)","trace[683075572] 'compare' (duration: 132.305886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:18.520741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.805763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:18.52084Z","caller":"traceutil/trace.go:171","msg":"trace[1208632253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"212.957637ms","start":"2026-04-23T16:58:18.307862Z","end":"2026-04-23T16:58:18.52082Z","steps":["trace[1208632253] 'range keys from in-memory index tree' (duration: 212.73345ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:18.52087Z","caller":"traceutil/trace.go:171","msg":"trace[1400015470] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"157.594963ms","start":"2026-04-23T16:58:18.363247Z","end":"2026-04-23T16:58:18.520842Z","steps":["trace[1400015470] 'process raft request' (duration: 103.844694ms)","trace[1400015470] 'compare' (duration: 53.633435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:18.520822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.795549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T16:58:18.521096Z","caller":"traceutil/trace.go:171","msg":"trace[397827881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"203.129198ms","start":"2026-04-23T16:58:18.317951Z","end":"2026-04-23T16:58:18.52108Z","steps":["trace[397827881] 'range keys from in-memory index tree' (duration: 202.705556ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:18.765549Z","caller":"traceutil/trace.go:171","msg":"trace[370408497] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"238.231896ms","start":"2026-04-23T16:58:18.527287Z","end":"2026-04-23T16:58:18.765519Z","steps":["trace[370408497] 'process raft request' (duration: 141.603738ms)","trace[370408497] 'compare' (duration: 96.424383ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:18.76559Z","caller":"traceutil/trace.go:171","msg":"trace[1647924401] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"238.081792ms","start":"2026-04-23T16:58:18.527396Z","end":"2026-04-23T16:58:18.765478Z","steps":["trace[1647924401] 'read index received' (duration: 141.504115ms)","trace[1647924401] 'applied index is now lower than readState.Index' (duration: 96.574617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:18.765776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.34954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-23T16:58:18.765866Z","caller":"traceutil/trace.go:171","msg":"trace[1347637667] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:399; }","duration":"238.482323ms","start":"2026-04-23T16:58:18.527356Z","end":"2026-04-23T16:58:18.765839Z","steps":["trace[1347637667] 'agreement among raft nodes before linearized reading' (duration: 238.301828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:19.16864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.952652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:58:19.168699Z","caller":"traceutil/trace.go:171","msg":"trace[279474787] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"232.926301ms","start":"2026-04-23T16:58:18.935763Z","end":"2026-04-23T16:58:19.168689Z","steps":["trace[279474787] 'read index received' (duration: 108.822604ms)","trace[279474787] 'applied index is now lower than readState.Index' (duration: 124.102787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:19.168772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.015714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-23T16:58:19.168785Z","caller":"traceutil/trace.go:171","msg":"trace[919633278] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:402; }","duration":"233.068895ms","start":"2026-04-23T16:58:18.935711Z","end":"2026-04-23T16:58:19.168779Z","steps":["trace[919633278] 'agreement among raft nodes before linearized reading' (duration: 233.021444ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:19.168797Z","caller":"traceutil/trace.go:171","msg":"trace[926750702] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"342.198799ms","start":"2026-04-23T16:58:18.82656Z","end":"2026-04-23T16:58:19.168759Z","steps":["trace[926750702] 'process raft request' (duration: 218.084702ms)","trace[926750702] 'compare' (duration: 123.86396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:19.168939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:18.826543Z","time spent":"342.328843ms","remote":"127.0.0.1:48674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:19.486381Z","caller":"traceutil/trace.go:171","msg":"trace[1852860692] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"310.755726ms","start":"2026-04-23T16:58:19.175558Z","end":"2026-04-23T16:58:19.486314Z","steps":["trace[1852860692] 'process raft request' (duration: 234.149588ms)","trace[1852860692] 'compare' (duration: 76.462544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:19.487384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:19.175537Z","time spent":"311.004994ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:58:19.490041Z","caller":"traceutil/trace.go:171","msg":"trace[199879580] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:412; }","duration":"181.422113ms","start":"2026-04-23T16:58:19.308608Z","end":"2026-04-23T16:58:19.49003Z","steps":["trace[199879580] 'read index received' (duration: 101.12982ms)","trace[199879580] 'applied index is now lower than readState.Index' (duration: 80.291663ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:19.490103Z","caller":"traceutil/trace.go:171","msg":"trace[215448871] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"311.881199ms","start":"2026-04-23T16:58:19.178215Z","end":"2026-04-23T16:58:19.490096Z","steps":["trace[215448871] 'process raft request' (duration: 311.795466ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:19.490111Z","caller":"traceutil/trace.go:171","msg":"trace[1566549236] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"313.186835ms","start":"2026-04-23T16:58:19.176897Z","end":"2026-04-23T16:58:19.490083Z","steps":["trace[1566549236] 'process raft request' (duration: 313.079672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:19.490152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:19.178189Z","time spent":"311.92939ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:58:19.49023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:19.176885Z","time spent":"313.287248ms","remote":"127.0.0.1:48644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:58:19.490234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.992399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T16:58:19.490308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.7077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:19.490331Z","caller":"traceutil/trace.go:171","msg":"trace[173530518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"181.767282ms","start":"2026-04-23T16:58:19.308557Z","end":"2026-04-23T16:58:19.490324Z","steps":["trace[173530518] 'agreement among raft nodes before linearized reading' (duration: 181.726591ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:19.490327Z","caller":"traceutil/trace.go:171","msg":"trace[1334201895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"149.149183ms","start":"2026-04-23T16:58:19.341156Z","end":"2026-04-23T16:58:19.490305Z","steps":["trace[1334201895] 'agreement among raft nodes before linearized reading' (duration: 148.980528ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:19.490359Z","caller":"traceutil/trace.go:171","msg":"trace[1203181402] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"314.125532ms","start":"2026-04-23T16:58:19.176215Z","end":"2026-04-23T16:58:19.490341Z","steps":["trace[1203181402] 'process raft request' (duration: 313.69698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:19.490442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:19.176162Z","time spent":"314.245834ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:58:26.935688Z","caller":"traceutil/trace.go:171","msg":"trace[1275837029] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"133.014366ms","start":"2026-04-23T16:58:26.802651Z","end":"2026-04-23T16:58:26.935666Z","steps":["trace[1275837029] 'process raft request' (duration: 132.824571ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:33.585781Z","caller":"traceutil/trace.go:171","msg":"trace[1909937302] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"546.965661ms","start":"2026-04-23T16:58:33.038779Z","end":"2026-04-23T16:58:33.585745Z","steps":["trace[1909937302] 'process raft request' (duration: 546.764396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:33.585964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:33.038752Z","time spent":"547.119325ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:58:38.069619Z","caller":"traceutil/trace.go:171","msg":"trace[43056666] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"133.760493ms","start":"2026-04-23T16:58:37.935835Z","end":"2026-04-23T16:58:38.069596Z","steps":["trace[43056666] 'process raft request' (duration: 132.34154ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:38.234423Z","caller":"traceutil/trace.go:171","msg":"trace[756324712] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"116.13722ms","start":"2026-04-23T16:58:38.118257Z","end":"2026-04-23T16:58:38.234394Z","steps":["trace[756324712] 'process raft request' (duration: 115.989467ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:41.959308Z","caller":"traceutil/trace.go:171","msg":"trace[1842659529] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"252.58228ms","start":"2026-04-23T16:58:41.706699Z","end":"2026-04-23T16:58:41.959282Z","steps":["trace[1842659529] 'process raft request' (duration: 181.389743ms)","trace[1842659529] 'compare' (duration: 71.102254ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:42.548504Z","caller":"traceutil/trace.go:171","msg":"trace[17130587] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"242.4925ms","start":"2026-04-23T16:58:42.305989Z","end":"2026-04-23T16:58:42.548481Z","steps":["trace[17130587] 'read index received' (duration: 242.364177ms)","trace[17130587] 'applied index is now lower than readState.Index' (duration: 127.383µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:42.548584Z","caller":"traceutil/trace.go:171","msg":"trace[1333429185] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"535.314342ms","start":"2026-04-23T16:58:42.013216Z","end":"2026-04-23T16:58:42.54853Z","steps":["trace[1333429185] 'process raft request' (duration: 535.122537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:42.548638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.629603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:42.548675Z","caller":"traceutil/trace.go:171","msg":"trace[814521667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"242.680715ms","start":"2026-04-23T16:58:42.305982Z","end":"2026-04-23T16:58:42.548663Z","steps":["trace[814521667] 'agreement among raft nodes before linearized reading' (duration: 242.610183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:42.548891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:42.013196Z","time spent":"535.473996ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:58:42.681072Z","caller":"traceutil/trace.go:171","msg":"trace[329268303] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"255.780656ms","start":"2026-04-23T16:58:42.425264Z","end":"2026-04-23T16:58:42.681045Z","steps":["trace[329268303] 'process raft request' (duration: 249.969452ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:42.681335Z","caller":"traceutil/trace.go:171","msg":"trace[662291839] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"137.630778ms","start":"2026-04-23T16:58:42.543648Z","end":"2026-04-23T16:58:42.681278Z","steps":["trace[662291839] 'process raft request' (duration: 137.29623ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:42.681567Z","caller":"traceutil/trace.go:171","msg":"trace[1436214989] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"132.96705ms","start":"2026-04-23T16:58:42.548575Z","end":"2026-04-23T16:58:42.681542Z","steps":["trace[1436214989] 'read index received' (duration: 126.546612ms)","trace[1436214989] 'applied index is now lower than readState.Index' (duration: 6.418408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:42.681735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.09857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:42.68175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.397931ms","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-23T16:58:42.681788Z","caller":"traceutil/trace.go:171","msg":"trace[1313961911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"132.199723ms","start":"2026-04-23T16:58:42.549571Z","end":"2026-04-23T16:58:42.681771Z","steps":["trace[1313961911] 'agreement among raft nodes before linearized reading' (duration: 132.106071ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:42.681814Z","caller":"traceutil/trace.go:171","msg":"trace[993465676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"269.512584ms","start":"2026-04-23T16:58:42.412284Z","end":"2026-04-23T16:58:42.681796Z","steps":["trace[993465676] 'agreement among raft nodes before linearized reading' (duration: 269.33734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.951411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642593985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.951459Z","caller":"traceutil/trace.go:171","msg":"trace[1774987516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.642656766s","start":"2026-04-23T16:58:43.308791Z","end":"2026-04-23T16:58:44.951447Z","steps":["trace[1774987516] 'range keys from in-memory index tree' (duration: 1.642540374s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.951487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:43.308731Z","time spent":"1.642749138s","remote":"127.0.0.1:53240","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-23T16:58:44.951566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.319923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.951635Z","caller":"traceutil/trace.go:171","msg":"trace[723685510] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"273.426896ms","start":"2026-04-23T16:58:44.67819Z","end":"2026-04-23T16:58:44.951617Z","steps":["trace[723685510] 'count revisions from in-memory index tree' (duration: 273.224632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.951782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.496478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.951811Z","caller":"traceutil/trace.go:171","msg":"trace[1158508588] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"273.561069ms","start":"2026-04-23T16:58:44.678241Z","end":"2026-04-23T16:58:44.951802Z","steps":["trace[1158508588] 'range keys from in-memory index tree' (duration: 273.382586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.951915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243410599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T16:58:44.951946Z","caller":"traceutil/trace.go:171","msg":"trace[570983509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.24348028s","start":"2026-04-23T16:58:43.708458Z","end":"2026-04-23T16:58:44.951938Z","steps":["trace[570983509] 'range keys from in-memory index tree' (duration: 1.243223554s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.951968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:43.708438Z","time spent":"1.243526511s","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T16:58:44.952188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.064291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T16:58:44.95222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.274664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952249Z","caller":"traceutil/trace.go:171","msg":"trace[949484644] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:627; }","duration":"109.312595ms","start":"2026-04-23T16:58:44.84293Z","end":"2026-04-23T16:58:44.952243Z","steps":["trace[949484644] 'range keys from in-memory index tree' (duration: 109.251823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.244644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:44.952268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.573851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952313Z","caller":"traceutil/trace.go:171","msg":"trace[1104465383] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"131.336145ms","start":"2026-04-23T16:58:44.820966Z","end":"2026-04-23T16:58:44.952302Z","steps":["trace[1104465383] 'range keys from in-memory index tree' (duration: 131.179563ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952222Z","caller":"traceutil/trace.go:171","msg":"trace[348166405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"989.101552ms","start":"2026-04-23T16:58:43.963113Z","end":"2026-04-23T16:58:44.952214Z","steps":["trace[348166405] 'range keys from in-memory index tree' (duration: 988.951028ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952324Z","caller":"traceutil/trace.go:171","msg":"trace[101684295] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"237.660023ms","start":"2026-04-23T16:58:44.714651Z","end":"2026-04-23T16:58:44.952311Z","steps":["trace[101684295] 'count revisions from in-memory index tree' (duration: 237.53648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:58:43.963015Z","time spent":"989.326716ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T16:58:44.952386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.501529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:44.952187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.417746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:44.952432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.900946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952448Z","caller":"traceutil/trace.go:171","msg":"trace[1860504010] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"236.957408ms","start":"2026-04-23T16:58:44.715486Z","end":"2026-04-23T16:58:44.952444Z","steps":["trace[1860504010] 'range keys from in-memory index tree' (duration: 236.831094ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952452Z","caller":"traceutil/trace.go:171","msg":"trace[810926936] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:627; }","duration":"273.705613ms","start":"2026-04-23T16:58:44.678737Z","end":"2026-04-23T16:58:44.952443Z","steps":["trace[810926936] 'range keys from in-memory index tree' (duration: 273.377095ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952425Z","caller":"traceutil/trace.go:171","msg":"trace[225515690] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:627; }","duration":"109.599751ms","start":"2026-04-23T16:58:44.842817Z","end":"2026-04-23T16:58:44.952417Z","steps":["trace[225515690] 'count revisions from in-memory index tree' (duration: 109.475589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.924161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952504Z","caller":"traceutil/trace.go:171","msg":"trace[682113608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"260.980683ms","start":"2026-04-23T16:58:44.691517Z","end":"2026-04-23T16:58:44.952497Z","steps":["trace[682113608] 'range keys from in-memory index tree' (duration: 260.830019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.592472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:44.952429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.770815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952571Z","caller":"traceutil/trace.go:171","msg":"trace[834846647] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"131.641362ms","start":"2026-04-23T16:58:44.820925Z","end":"2026-04-23T16:58:44.952566Z","steps":["trace[834846647] 'count revisions from in-memory index tree' (duration: 131.538921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.910301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952654Z","caller":"traceutil/trace.go:171","msg":"trace[1342753870] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"260.956632ms","start":"2026-04-23T16:58:44.691691Z","end":"2026-04-23T16:58:44.952648Z","steps":["trace[1342753870] 'count revisions from in-memory index tree' (duration: 260.86681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.131264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:44.952715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.244754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:44.952739Z","caller":"traceutil/trace.go:171","msg":"trace[265341187] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"238.171264ms","start":"2026-04-23T16:58:44.714562Z","end":"2026-04-23T16:58:44.952734Z","steps":["trace[265341187] 'range keys from in-memory index tree' (duration: 238.091673ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952617Z","caller":"traceutil/trace.go:171","msg":"trace[366875901] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:627; }","duration":"110.017841ms","start":"2026-04-23T16:58:44.84256Z","end":"2026-04-23T16:58:44.952578Z","steps":["trace[366875901] 'range keys from in-memory index tree' (duration: 109.710934ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952814Z","caller":"traceutil/trace.go:171","msg":"trace[606207570] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:627; }","duration":"131.361846ms","start":"2026-04-23T16:58:44.821382Z","end":"2026-04-23T16:58:44.952744Z","steps":["trace[606207570] 'range keys from in-memory index tree' (duration: 131.186732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.672455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:58:44.952872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.746237ms","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-23T16:58:44.952898Z","caller":"traceutil/trace.go:171","msg":"trace[106547302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"238.772578ms","start":"2026-04-23T16:58:44.714119Z","end":"2026-04-23T16:58:44.952891Z","steps":["trace[106547302] 'range keys from in-memory index tree' (duration: 238.669336ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:44.952897Z","caller":"traceutil/trace.go:171","msg":"trace[893767397] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:627; }","duration":"260.739838ms","start":"2026-04-23T16:58:44.69215Z","end":"2026-04-23T16:58:44.95289Z","steps":["trace[893767397] 'range keys from in-memory index tree' (duration: 260.601104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:44.952858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.144127ms","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-23T16:58:44.952969Z","caller":"traceutil/trace.go:171","msg":"trace[1209345004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"243.27394ms","start":"2026-04-23T16:58:44.709688Z","end":"2026-04-23T16:58:44.952962Z","steps":["trace[1209345004] 'range keys from in-memory index tree' (duration: 243.072626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:46.501123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.794714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:58:46.501215Z","caller":"traceutil/trace.go:171","msg":"trace[1895477646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"192.946058ms","start":"2026-04-23T16:58:46.30825Z","end":"2026-04-23T16:58:46.501196Z","steps":["trace[1895477646] 'range keys from in-memory index tree' (duration: 192.727533ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:50.90601Z","caller":"traceutil/trace.go:171","msg":"trace[372773394] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"187.197675ms","start":"2026-04-23T16:58:50.718788Z","end":"2026-04-23T16:58:50.905986Z","steps":["trace[372773394] 'read index received' (duration: 135.84322ms)","trace[372773394] 'applied index is now lower than readState.Index' (duration: 51.353545ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:58:50.906118Z","caller":"traceutil/trace.go:171","msg":"trace[1855879531] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"187.360869ms","start":"2026-04-23T16:58:50.71868Z","end":"2026-04-23T16:58:50.906041Z","steps":["trace[1855879531] 'process raft request' (duration: 135.986964ms)","trace[1855879531] 'compare' (duration: 51.15536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:58:50.90622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.41384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T16:58:50.906275Z","caller":"traceutil/trace.go:171","msg":"trace[331229987] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:710; }","duration":"187.519402ms","start":"2026-04-23T16:58:50.718745Z","end":"2026-04-23T16:58:50.906264Z","steps":["trace[331229987] 'agreement among raft nodes before linearized reading' (duration: 187.413959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:50.906613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.126603ms","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-23T16:58:50.906657Z","caller":"traceutil/trace.go:171","msg":"trace[1783167740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:710; }","duration":"158.207895ms","start":"2026-04-23T16:58:50.748438Z","end":"2026-04-23T16:58:50.906645Z","steps":["trace[1783167740] 'agreement among raft nodes before linearized reading' (duration: 158.050191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:58:50.906923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.773769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-23T16:58:50.907096Z","caller":"traceutil/trace.go:171","msg":"trace[319550296] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:710; }","duration":"182.972924ms","start":"2026-04-23T16:58:50.724057Z","end":"2026-04-23T16:58:50.90703Z","steps":["trace[319550296] 'agreement among raft nodes before linearized reading' (duration: 182.387591ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:59.224636Z","caller":"traceutil/trace.go:171","msg":"trace[2057858636] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"148.623055ms","start":"2026-04-23T16:58:59.07599Z","end":"2026-04-23T16:58:59.224614Z","steps":["trace[2057858636] 'process raft request' (duration: 148.585395ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:59.224706Z","caller":"traceutil/trace.go:171","msg":"trace[990050665] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"149.630357ms","start":"2026-04-23T16:58:59.075043Z","end":"2026-04-23T16:58:59.224673Z","steps":["trace[990050665] 'process raft request' (duration: 149.494084ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:58:59.224646Z","caller":"traceutil/trace.go:171","msg":"trace[1831999765] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"149.624647ms","start":"2026-04-23T16:58:59.074997Z","end":"2026-04-23T16:58:59.224621Z","steps":["trace[1831999765] 'process raft request' (duration: 105.181852ms)","trace[1831999765] 'compare' (duration: 44.23366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:01.575868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.756715ms","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-23T16:59:01.57602Z","caller":"traceutil/trace.go:171","msg":"trace[1139861155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"590.95413ms","start":"2026-04-23T16:59:00.985042Z","end":"2026-04-23T16:59:01.575997Z","steps":["trace[1139861155] 'range keys from in-memory index tree' (duration: 589.593093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:01.576103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:00.985024Z","time spent":"591.062941ms","remote":"127.0.0.1:48470","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-23T16:59:01.585252Z","caller":"traceutil/trace.go:171","msg":"trace[1227323166] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"457.370074ms","start":"2026-04-23T16:59:01.127864Z","end":"2026-04-23T16:59:01.585234Z","steps":["trace[1227323166] 'read index received' (duration: 457.322943ms)","trace[1227323166] 'applied index is now lower than readState.Index' (duration: 46.261µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:01.585345Z","caller":"traceutil/trace.go:171","msg":"trace[46816974] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"476.767184ms","start":"2026-04-23T16:59:01.108548Z","end":"2026-04-23T16:59:01.585315Z","steps":["trace[46816974] 'process raft request' (duration: 476.559289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:01.585453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.589138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T16:59:01.585509Z","caller":"traceutil/trace.go:171","msg":"trace[2019730626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"457.67425ms","start":"2026-04-23T16:59:01.12782Z","end":"2026-04-23T16:59:01.585495Z","steps":["trace[2019730626] 'agreement among raft nodes before linearized reading' (duration: 457.497367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:01.585513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:01.108524Z","time spent":"476.902736ms","remote":"127.0.0.1:48448","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-23T16:59:01.585542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:01.127808Z","time spent":"457.725681ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:59:01.586987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.751314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T16:59:01.587019Z","caller":"traceutil/trace.go:171","msg":"trace[1027292607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"358.812505ms","start":"2026-04-23T16:59:01.228198Z","end":"2026-04-23T16:59:01.58701Z","steps":["trace[1027292607] 'agreement among raft nodes before linearized reading' (duration: 358.725883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:01.58704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:01.228184Z","time spent":"358.851296ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T16:59:01.587214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.909397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T16:59:01.587252Z","caller":"traceutil/trace.go:171","msg":"trace[1724398604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"358.973929ms","start":"2026-04-23T16:59:01.228267Z","end":"2026-04-23T16:59:01.587241Z","steps":["trace[1724398604] 'agreement among raft nodes before linearized reading' (duration: 358.893737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:01.587363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:01.22823Z","time spent":"359.038539ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T16:59:01.587469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.408727ms","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-23T16:59:01.587553Z","caller":"traceutil/trace.go:171","msg":"trace[1927338964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"358.743934ms","start":"2026-04-23T16:59:01.228795Z","end":"2026-04-23T16:59:01.587539Z","steps":["trace[1927338964] 'agreement among raft nodes before linearized reading' (duration: 358.327895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:01.587615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:01.228784Z","time spent":"358.819556ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T16:59:05.523902Z","caller":"traceutil/trace.go:171","msg":"trace[172033933] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"167.932704ms","start":"2026-04-23T16:59:05.355923Z","end":"2026-04-23T16:59:05.523856Z","steps":["trace[172033933] 'process raft request' (duration: 162.565679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:05.708666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.632885ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T16:59:05.709014Z","caller":"traceutil/trace.go:171","msg":"trace[1613817849] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"100.198368ms","start":"2026-04-23T16:59:05.60879Z","end":"2026-04-23T16:59:05.708989Z","steps":["trace[1613817849] 'read index received' (duration: 876.687µs)","trace[1613817849] 'applied index is now lower than readState.Index' (duration: 99.320471ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:05.709191Z","caller":"traceutil/trace.go:171","msg":"trace[609397107] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"148.858031ms","start":"2026-04-23T16:59:05.560256Z","end":"2026-04-23T16:59:05.709114Z","steps":["trace[609397107] 'process raft request' (duration: 148.604236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:05.709393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.596787ms","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-23T16:59:05.709484Z","caller":"traceutil/trace.go:171","msg":"trace[448509643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"100.73293ms","start":"2026-04-23T16:59:05.608735Z","end":"2026-04-23T16:59:05.709468Z","steps":["trace[448509643] 'agreement among raft nodes before linearized reading' (duration: 100.438393ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:05.856373Z","caller":"traceutil/trace.go:171","msg":"trace[1089738299] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"141.549178ms","start":"2026-04-23T16:59:05.714793Z","end":"2026-04-23T16:59:05.856342Z","steps":["trace[1089738299] 'process raft request' (duration: 108.978071ms)","trace[1089738299] 'compare' (duration: 32.417534ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:05.938433Z","caller":"traceutil/trace.go:171","msg":"trace[71016484] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"223.452518ms","start":"2026-04-23T16:59:05.714945Z","end":"2026-04-23T16:59:05.938397Z","steps":["trace[71016484] 'process raft request' (duration: 223.111392ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:05.938473Z","caller":"traceutil/trace.go:171","msg":"trace[2020054781] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"223.441938ms","start":"2026-04-23T16:59:05.714996Z","end":"2026-04-23T16:59:05.938438Z","steps":["trace[2020054781] 'process raft request' (duration: 223.232014ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:05.93853Z","caller":"traceutil/trace.go:171","msg":"trace[232307099] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"222.547132ms","start":"2026-04-23T16:59:05.71597Z","end":"2026-04-23T16:59:05.938517Z","steps":["trace[232307099] 'process raft request' (duration: 222.353548ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:05.938452Z","caller":"traceutil/trace.go:171","msg":"trace[2130808618] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"222.312127ms","start":"2026-04-23T16:59:05.716111Z","end":"2026-04-23T16:59:05.938423Z","steps":["trace[2130808618] 'process raft request' (duration: 222.260076ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:05.938478Z","caller":"traceutil/trace.go:171","msg":"trace[876841083] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"222.594182ms","start":"2026-04-23T16:59:05.715862Z","end":"2026-04-23T16:59:05.938456Z","steps":["trace[876841083] 'process raft request' (duration: 222.417719ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.003124Z","caller":"traceutil/trace.go:171","msg":"trace[1378324919] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"142.523007ms","start":"2026-04-23T16:59:05.860575Z","end":"2026-04-23T16:59:06.003098Z","steps":["trace[1378324919] 'process raft request' (duration: 142.443015ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.003156Z","caller":"traceutil/trace.go:171","msg":"trace[502874577] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"142.64313ms","start":"2026-04-23T16:59:05.860475Z","end":"2026-04-23T16:59:06.003118Z","steps":["trace[502874577] 'process raft request' (duration: 114.52235ms)","trace[502874577] 'compare' (duration: 27.797934ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:06.237414Z","caller":"traceutil/trace.go:171","msg":"trace[1173317209] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"103.715785ms","start":"2026-04-23T16:59:06.133676Z","end":"2026-04-23T16:59:06.237392Z","steps":["trace[1173317209] 'process raft request' (duration: 99.082054ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.306757Z","caller":"traceutil/trace.go:171","msg":"trace[2120543631] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"170.980971ms","start":"2026-04-23T16:59:06.135755Z","end":"2026-04-23T16:59:06.306736Z","steps":["trace[2120543631] 'process raft request' (duration: 170.941371ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.306793Z","caller":"traceutil/trace.go:171","msg":"trace[158527707] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"171.779088ms","start":"2026-04-23T16:59:06.134985Z","end":"2026-04-23T16:59:06.306764Z","steps":["trace[158527707] 'process raft request' (duration: 171.615495ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.634286Z","caller":"traceutil/trace.go:171","msg":"trace[965155071] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"166.221039ms","start":"2026-04-23T16:59:06.468044Z","end":"2026-04-23T16:59:06.634265Z","steps":["trace[965155071] 'process raft request' (duration: 166.063816ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.634393Z","caller":"traceutil/trace.go:171","msg":"trace[1233172405] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"159.823245ms","start":"2026-04-23T16:59:06.474549Z","end":"2026-04-23T16:59:06.634372Z","steps":["trace[1233172405] 'process raft request' (duration: 159.669642ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:06.89624Z","caller":"traceutil/trace.go:171","msg":"trace[1416372292] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"190.374949ms","start":"2026-04-23T16:59:06.705841Z","end":"2026-04-23T16:59:06.896216Z","steps":["trace[1416372292] 'process raft request' (duration: 188.535134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:07.414645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.707919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:07.414728Z","caller":"traceutil/trace.go:171","msg":"trace[1835369414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"105.840042ms","start":"2026-04-23T16:59:07.308866Z","end":"2026-04-23T16:59:07.414706Z","steps":["trace[1835369414] 'range keys from in-memory index tree' (duration: 105.638598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:07.415457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.685317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-23T16:59:07.415523Z","caller":"traceutil/trace.go:171","msg":"trace[1756130967] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:836; }","duration":"397.763408ms","start":"2026-04-23T16:59:07.017742Z","end":"2026-04-23T16:59:07.415505Z","steps":["trace[1756130967] 'range keys from in-memory index tree' (duration: 396.966232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:07.415557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:07.017697Z","time spent":"397.852159ms","remote":"127.0.0.1:48306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050284,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-23T16:59:07.750037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.915069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-23T16:59:07.750128Z","caller":"traceutil/trace.go:171","msg":"trace[1899715056] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:838; }","duration":"115.066181ms","start":"2026-04-23T16:59:07.635044Z","end":"2026-04-23T16:59:07.75011Z","steps":["trace[1899715056] 'range keys from in-memory index tree' (duration: 113.835247ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:08.107895Z","caller":"traceutil/trace.go:171","msg":"trace[1741265565] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"161.124978ms","start":"2026-04-23T16:59:07.946748Z","end":"2026-04-23T16:59:08.107873Z","steps":["trace[1741265565] 'process raft request' (duration: 160.982945ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:08.802697Z","caller":"traceutil/trace.go:171","msg":"trace[757173704] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"855.817832ms","start":"2026-04-23T16:59:07.946858Z","end":"2026-04-23T16:59:08.802676Z","steps":["trace[757173704] 'process raft request' (duration: 855.75873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:08.80284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:07.946854Z","time spent":"855.912123ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:08.802909Z","caller":"traceutil/trace.go:171","msg":"trace[1610742457] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"856.075547ms","start":"2026-04-23T16:59:07.946809Z","end":"2026-04-23T16:59:08.802884Z","steps":["trace[1610742457] 'process raft request' (duration: 804.65675ms)","trace[1610742457] 'compare' (duration: 51.009029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:08.803041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:07.946797Z","time spent":"856.179418ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:08.803263Z","caller":"traceutil/trace.go:171","msg":"trace[1827238341] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"856.402123ms","start":"2026-04-23T16:59:07.946854Z","end":"2026-04-23T16:59:08.803256Z","steps":["trace[1827238341] 'process raft request' (duration: 855.804321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:08.803295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:07.946841Z","time spent":"856.436213ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:08.803358Z","caller":"traceutil/trace.go:171","msg":"trace[430241319] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"856.327701ms","start":"2026-04-23T16:59:07.946985Z","end":"2026-04-23T16:59:08.803313Z","steps":["trace[430241319] 'process raft request' (duration: 855.937743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:08.803524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:07.946968Z","time spent":"856.485904ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:12.525764Z","caller":"traceutil/trace.go:171","msg":"trace[832248782] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"155.840645ms","start":"2026-04-23T16:59:12.369891Z","end":"2026-04-23T16:59:12.525731Z","steps":["trace[832248782] 'process raft request' (duration: 155.607541ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:13.001073Z","caller":"traceutil/trace.go:171","msg":"trace[180581213] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"138.056423ms","start":"2026-04-23T16:59:12.862994Z","end":"2026-04-23T16:59:13.001051Z","steps":["trace[180581213] 'process raft request' (duration: 137.91382ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:13.044599Z","caller":"traceutil/trace.go:171","msg":"trace[1281801019] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:902; }","duration":"163.648317ms","start":"2026-04-23T16:59:12.880926Z","end":"2026-04-23T16:59:13.044574Z","steps":["trace[1281801019] 'read index received' (duration: 119.958683ms)","trace[1281801019] 'applied index is now lower than readState.Index' (duration: 43.688724ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:13.044762Z","caller":"traceutil/trace.go:171","msg":"trace[2099554429] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"177.884833ms","start":"2026-04-23T16:59:12.866769Z","end":"2026-04-23T16:59:13.044653Z","steps":["trace[2099554429] 'process raft request' (duration: 177.659899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:13.044832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.885631ms","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-23T16:59:13.044853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.85362ms","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-23T16:59:13.044893Z","caller":"traceutil/trace.go:171","msg":"trace[541681508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"163.992802ms","start":"2026-04-23T16:59:12.880886Z","end":"2026-04-23T16:59:13.044879Z","steps":["trace[541681508] 'agreement among raft nodes before linearized reading' (duration: 163.805569ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:13.044895Z","caller":"traceutil/trace.go:171","msg":"trace[1051009383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"163.952802ms","start":"2026-04-23T16:59:12.880932Z","end":"2026-04-23T16:59:13.044884Z","steps":["trace[1051009383] 'agreement among raft nodes before linearized reading' (duration: 163.788949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:13.044948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.418412ms","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-23T16:59:13.045041Z","caller":"traceutil/trace.go:171","msg":"trace[26440756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"163.572245ms","start":"2026-04-23T16:59:12.881449Z","end":"2026-04-23T16:59:13.045021Z","steps":["trace[26440756] 'agreement among raft nodes before linearized reading' (duration: 163.31929ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:13.358499Z","caller":"traceutil/trace.go:171","msg":"trace[476787535] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"309.492524ms","start":"2026-04-23T16:59:13.048978Z","end":"2026-04-23T16:59:13.35847Z","steps":["trace[476787535] 'process raft request' (duration: 298.666102ms)","trace[476787535] 'compare' (duration: 10.584967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:13.358645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.048953Z","time spent":"309.617026ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:13.468452Z","caller":"traceutil/trace.go:171","msg":"trace[1954744583] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"418.140265ms","start":"2026-04-23T16:59:13.05028Z","end":"2026-04-23T16:59:13.46842Z","steps":["trace[1954744583] 'process raft request' (duration: 412.869297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:13.468658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.050257Z","time spent":"418.291627ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:13.468654Z","caller":"traceutil/trace.go:171","msg":"trace[1115527097] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:904; }","duration":"322.516037ms","start":"2026-04-23T16:59:13.146077Z","end":"2026-04-23T16:59:13.468593Z","steps":["trace[1115527097] 'read index received' (duration: 201.580918ms)","trace[1115527097] 'applied index is now lower than readState.Index' (duration: 120.928399ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:13.468677Z","caller":"traceutil/trace.go:171","msg":"trace[190839903] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"417.123016ms","start":"2026-04-23T16:59:13.051511Z","end":"2026-04-23T16:59:13.468634Z","steps":["trace[190839903] 'process raft request' (duration: 416.78058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:13.469004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.051494Z","time spent":"417.409542ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:59:13.468807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.718841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-4zx9x\" ","response":"range_response_count:1 size:4776"} {"level":"info","ts":"2026-04-23T16:59:13.46921Z","caller":"traceutil/trace.go:171","msg":"trace[1606434796] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-4zx9x; range_end:; response_count:1; response_revision:883; }","duration":"323.053907ms","start":"2026-04-23T16:59:13.14604Z","end":"2026-04-23T16:59:13.469094Z","steps":["trace[1606434796] 'agreement among raft nodes before linearized reading' (duration: 322.638719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:13.469288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.146024Z","time spent":"323.245341ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4800,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-4zx9x\" "} {"level":"info","ts":"2026-04-23T16:59:13.469238Z","caller":"traceutil/trace.go:171","msg":"trace[881066155] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"319.287817ms","start":"2026-04-23T16:59:13.149937Z","end":"2026-04-23T16:59:13.469225Z","steps":["trace[881066155] 'process raft request' (duration: 318.41457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:13.469563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.149918Z","time spent":"319.606103ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5316,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:13.723956Z","caller":"traceutil/trace.go:171","msg":"trace[254463579] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"185.553611ms","start":"2026-04-23T16:59:13.538385Z","end":"2026-04-23T16:59:13.723938Z","steps":["trace[254463579] 'process raft request' (duration: 185.446428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.01414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.027401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:59:14.014355Z","caller":"traceutil/trace.go:171","msg":"trace[1632648562] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"335.483467ms","start":"2026-04-23T16:59:13.678859Z","end":"2026-04-23T16:59:14.014343Z","steps":["trace[1632648562] 'process raft request' (duration: 335.406016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.014445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.678835Z","time spent":"335.58779ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T16:59:14.014354Z","caller":"traceutil/trace.go:171","msg":"trace[1711976538] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"396.096214ms","start":"2026-04-23T16:59:13.618238Z","end":"2026-04-23T16:59:14.014334Z","steps":["trace[1711976538] 'process raft request' (duration: 190.816028ms)","trace[1711976538] 'compare' (duration: 204.95412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:14.01458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.618215Z","time spent":"396.294637ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:14.031587Z","caller":"traceutil/trace.go:171","msg":"trace[1452984036] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"303.449649ms","start":"2026-04-23T16:59:13.728117Z","end":"2026-04-23T16:59:14.031566Z","steps":["trace[1452984036] 'process raft request' (duration: 303.146034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.031792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.728104Z","time spent":"303.609962ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1448,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:14.031982Z","caller":"traceutil/trace.go:171","msg":"trace[417390457] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"303.453049ms","start":"2026-04-23T16:59:13.728473Z","end":"2026-04-23T16:59:14.031926Z","steps":["trace[417390457] 'process raft request' (duration: 302.932419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.033219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.728459Z","time spent":"304.727144ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:14.032178Z","caller":"traceutil/trace.go:171","msg":"trace[139988078] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"299.553827ms","start":"2026-04-23T16:59:13.732297Z","end":"2026-04-23T16:59:14.03185Z","steps":["trace[139988078] 'process raft request' (duration: 299.482836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.033324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:13.73229Z","time spent":"301.012735ms","remote":"127.0.0.1:48674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:14.140677Z","caller":"traceutil/trace.go:171","msg":"trace[1380595981] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"101.077968ms","start":"2026-04-23T16:59:14.039558Z","end":"2026-04-23T16:59:14.140636Z","steps":["trace[1380595981] 'process raft request' (duration: 94.030209ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:14.265233Z","caller":"traceutil/trace.go:171","msg":"trace[1339253855] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"103.722197ms","start":"2026-04-23T16:59:14.161479Z","end":"2026-04-23T16:59:14.265202Z","steps":["trace[1339253855] 'read index received' (duration: 102.613446ms)","trace[1339253855] 'applied index is now lower than readState.Index' (duration: 1.107301ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:14.265357Z","caller":"traceutil/trace.go:171","msg":"trace[1388819735] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"111.98007ms","start":"2026-04-23T16:59:14.153325Z","end":"2026-04-23T16:59:14.265305Z","steps":["trace[1388819735] 'process raft request' (duration: 110.806918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.26551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.796927ms","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-23T16:59:14.265572Z","caller":"traceutil/trace.go:171","msg":"trace[42033669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"117.905949ms","start":"2026-04-23T16:59:14.147647Z","end":"2026-04-23T16:59:14.265553Z","steps":["trace[42033669] 'agreement among raft nodes before linearized reading' (duration: 117.671465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:14.265579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.687882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-s52jq\" ","response":"range_response_count:1 size:4776"} {"level":"info","ts":"2026-04-23T16:59:14.265839Z","caller":"traceutil/trace.go:171","msg":"trace[1658205631] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-s52jq; range_end:; response_count:1; response_revision:896; }","duration":"112.954477ms","start":"2026-04-23T16:59:14.152807Z","end":"2026-04-23T16:59:14.265762Z","steps":["trace[1658205631] 'agreement among raft nodes before linearized reading' (duration: 112.503689ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:14.353042Z","caller":"traceutil/trace.go:171","msg":"trace[1013564484] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"189.754828ms","start":"2026-04-23T16:59:14.163258Z","end":"2026-04-23T16:59:14.353013Z","steps":["trace[1013564484] 'process raft request' (duration: 189.615815ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:14.390761Z","caller":"traceutil/trace.go:171","msg":"trace[1074509826] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"119.300146ms","start":"2026-04-23T16:59:14.271434Z","end":"2026-04-23T16:59:14.390734Z","steps":["trace[1074509826] 'process raft request' (duration: 97.507252ms)","trace[1074509826] 'compare' (duration: 21.575639ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:14.391855Z","caller":"traceutil/trace.go:171","msg":"trace[250130100] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"118.407578ms","start":"2026-04-23T16:59:14.273426Z","end":"2026-04-23T16:59:14.391833Z","steps":["trace[250130100] 'process raft request' (duration: 117.247347ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:14.39209Z","caller":"traceutil/trace.go:171","msg":"trace[32224472] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"108.75831ms","start":"2026-04-23T16:59:14.283287Z","end":"2026-04-23T16:59:14.392046Z","steps":["trace[32224472] 'process raft request' (duration: 107.745842ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:15.279045Z","caller":"traceutil/trace.go:171","msg":"trace[1439154613] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"117.04559ms","start":"2026-04-23T16:59:15.161969Z","end":"2026-04-23T16:59:15.279014Z","steps":["trace[1439154613] 'process raft request' (duration: 100.365914ms)","trace[1439154613] 'compare' (duration: 16.579225ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:15.390732Z","caller":"traceutil/trace.go:171","msg":"trace[1792108144] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"102.797417ms","start":"2026-04-23T16:59:15.287913Z","end":"2026-04-23T16:59:15.39071Z","steps":["trace[1792108144] 'process raft request' (duration: 99.137751ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:15.391204Z","caller":"traceutil/trace.go:171","msg":"trace[1161427339] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"102.488273ms","start":"2026-04-23T16:59:15.288693Z","end":"2026-04-23T16:59:15.391182Z","steps":["trace[1161427339] 'process raft request' (duration: 101.967253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:15.822168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.322181ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T16:59:16.170965Z","caller":"traceutil/trace.go:171","msg":"trace[987973858] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"137.731705ms","start":"2026-04-23T16:59:16.033207Z","end":"2026-04-23T16:59:16.170939Z","steps":["trace[987973858] 'read index received' (duration: 137.521942ms)","trace[987973858] 'applied index is now lower than readState.Index' (duration: 208.543µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:16.17098Z","caller":"traceutil/trace.go:171","msg":"trace[1902151430] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"152.669378ms","start":"2026-04-23T16:59:16.018289Z","end":"2026-04-23T16:59:16.170959Z","steps":["trace[1902151430] 'process raft request' (duration: 152.464174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:16.171116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.891108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:16.171166Z","caller":"traceutil/trace.go:171","msg":"trace[1708779032] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:919; }","duration":"137.98596ms","start":"2026-04-23T16:59:16.033167Z","end":"2026-04-23T16:59:16.171153Z","steps":["trace[1708779032] 'agreement among raft nodes before linearized reading' (duration: 137.870958ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:17.122681Z","caller":"traceutil/trace.go:171","msg":"trace[1349813816] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"350.283685ms","start":"2026-04-23T16:59:16.772378Z","end":"2026-04-23T16:59:17.122662Z","steps":["trace[1349813816] 'process raft request' (duration: 349.786896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:17.122801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:16.77236Z","time spent":"350.375287ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:17.354077Z","caller":"traceutil/trace.go:171","msg":"trace[1945766453] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"224.235222ms","start":"2026-04-23T16:59:17.129819Z","end":"2026-04-23T16:59:17.354054Z","steps":["trace[1945766453] 'process raft request' (duration: 224.174331ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:17.354348Z","caller":"traceutil/trace.go:171","msg":"trace[1000306756] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"224.7011ms","start":"2026-04-23T16:59:17.129636Z","end":"2026-04-23T16:59:17.354337Z","steps":["trace[1000306756] 'process raft request' (duration: 136.091072ms)","trace[1000306756] 'compare' (duration: 87.851045ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:17.354643Z","caller":"traceutil/trace.go:171","msg":"trace[2124758633] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"224.897474ms","start":"2026-04-23T16:59:17.129731Z","end":"2026-04-23T16:59:17.354629Z","steps":["trace[2124758633] 'process raft request' (duration: 224.12361ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:17.572274Z","caller":"traceutil/trace.go:171","msg":"trace[158155001] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"155.87022ms","start":"2026-04-23T16:59:17.416378Z","end":"2026-04-23T16:59:17.572248Z","steps":["trace[158155001] 'read index received' (duration: 155.599915ms)","trace[158155001] 'applied index is now lower than readState.Index' (duration: 268.755µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:17.572346Z","caller":"traceutil/trace.go:171","msg":"trace[347075208] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"205.43765ms","start":"2026-04-23T16:59:17.366877Z","end":"2026-04-23T16:59:17.572314Z","steps":["trace[347075208] 'process raft request' (duration: 205.210336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:17.572458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.197337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:17.572519Z","caller":"traceutil/trace.go:171","msg":"trace[792953387] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:926; }","duration":"145.282828ms","start":"2026-04-23T16:59:17.427216Z","end":"2026-04-23T16:59:17.572498Z","steps":["trace[792953387] 'agreement among raft nodes before linearized reading' (duration: 145.185677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:17.572628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.219756ms","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-23T16:59:17.572735Z","caller":"traceutil/trace.go:171","msg":"trace[607375475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"156.374238ms","start":"2026-04-23T16:59:17.416334Z","end":"2026-04-23T16:59:17.572708Z","steps":["trace[607375475] 'agreement among raft nodes before linearized reading' (duration: 156.063983ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:19.793131Z","caller":"traceutil/trace.go:171","msg":"trace[490989944] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"111.742606ms","start":"2026-04-23T16:59:19.681367Z","end":"2026-04-23T16:59:19.793109Z","steps":["trace[490989944] 'process raft request' (duration: 111.615243ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:20.042736Z","caller":"traceutil/trace.go:171","msg":"trace[612583757] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"258.773341ms","start":"2026-04-23T16:59:19.783928Z","end":"2026-04-23T16:59:20.042701Z","steps":["trace[612583757] 'process raft request' (duration: 258.69742ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:20.042744Z","caller":"traceutil/trace.go:171","msg":"trace[1957717593] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"361.19657ms","start":"2026-04-23T16:59:19.681496Z","end":"2026-04-23T16:59:20.042693Z","steps":["trace[1957717593] 'process raft request' (duration: 360.945995ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:20.042801Z","caller":"traceutil/trace.go:171","msg":"trace[131871025] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"360.485318ms","start":"2026-04-23T16:59:19.68227Z","end":"2026-04-23T16:59:20.042755Z","steps":["trace[131871025] 'process raft request' (duration: 360.312145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:20.04293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:19.681454Z","time spent":"361.387693ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:59:20.043029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:19.682249Z","time spent":"360.655ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:30.200603Z","caller":"traceutil/trace.go:171","msg":"trace[265396919] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"181.987668ms","start":"2026-04-23T16:59:30.018586Z","end":"2026-04-23T16:59:30.200574Z","steps":["trace[265396919] 'process raft request' (duration: 181.794855ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:30.331241Z","caller":"traceutil/trace.go:171","msg":"trace[1901454580] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"126.789285ms","start":"2026-04-23T16:59:30.20443Z","end":"2026-04-23T16:59:30.331219Z","steps":["trace[1901454580] 'process raft request' (duration: 54.39844ms)","trace[1901454580] 'compare' (duration: 72.186061ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:30.331347Z","caller":"traceutil/trace.go:171","msg":"trace[470739936] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"126.729094ms","start":"2026-04-23T16:59:30.204599Z","end":"2026-04-23T16:59:30.331328Z","steps":["trace[470739936] 'process raft request' (duration: 126.585191ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:30.404889Z","caller":"traceutil/trace.go:171","msg":"trace[1935562717] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"195.905074ms","start":"2026-04-23T16:59:30.208954Z","end":"2026-04-23T16:59:30.404859Z","steps":["trace[1935562717] 'process raft request' (duration: 195.701141ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:30.404927Z","caller":"traceutil/trace.go:171","msg":"trace[1431401714] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"178.608872ms","start":"2026-04-23T16:59:30.226283Z","end":"2026-04-23T16:59:30.404892Z","steps":["trace[1431401714] 'process raft request' (duration: 178.49633ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:30.490212Z","caller":"traceutil/trace.go:171","msg":"trace[42996567] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"172.59355ms","start":"2026-04-23T16:59:30.317597Z","end":"2026-04-23T16:59:30.49019Z","steps":["trace[42996567] 'process raft request' (duration: 143.028419ms)","trace[42996567] 'compare' (duration: 29.096212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:30.490561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.874078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:30.491886Z","caller":"traceutil/trace.go:171","msg":"trace[229576172] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1008; }","duration":"150.236271ms","start":"2026-04-23T16:59:30.34163Z","end":"2026-04-23T16:59:30.491867Z","steps":["trace[229576172] 'agreement among raft nodes before linearized reading' (duration: 148.881008ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:30.642404Z","caller":"traceutil/trace.go:171","msg":"trace[2113911453] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"113.091104ms","start":"2026-04-23T16:59:30.529274Z","end":"2026-04-23T16:59:30.642365Z","steps":["trace[2113911453] 'process raft request' (duration: 94.191573ms)","trace[2113911453] 'compare' (duration: 18.782899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:40.41164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.177044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:59:40.411657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.076455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:40.411716Z","caller":"traceutil/trace.go:171","msg":"trace[845166872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"103.297355ms","start":"2026-04-23T16:59:40.308401Z","end":"2026-04-23T16:59:40.411699Z","steps":["trace[845166872] 'range keys from in-memory index tree' (duration: 103.061141ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.411724Z","caller":"traceutil/trace.go:171","msg":"trace[1898256794] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1055; }","duration":"249.167176ms","start":"2026-04-23T16:59:40.162542Z","end":"2026-04-23T16:59:40.411709Z","steps":["trace[1898256794] 'count revisions from in-memory index tree' (duration: 249.024044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:40.41178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.195171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e8efe72-65a0-4497-9e15-3873268f8951\" ","response":"range_response_count:1 size:3578"} {"level":"warn","ts":"2026-04-23T16:59:40.41178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.514196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T16:59:40.411843Z","caller":"traceutil/trace.go:171","msg":"trace[725340850] range","detail":"{range_begin:/registry/masterleases/199.19.213.43; range_end:; response_count:1; response_revision:1055; }","duration":"253.624838ms","start":"2026-04-23T16:59:40.158204Z","end":"2026-04-23T16:59:40.411829Z","steps":["trace[725340850] 'range keys from in-memory index tree' (duration: 253.368284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:40.411907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.711814ms","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-23T16:59:40.411954Z","caller":"traceutil/trace.go:171","msg":"trace[1038407935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"100.767445ms","start":"2026-04-23T16:59:40.311172Z","end":"2026-04-23T16:59:40.411939Z","steps":["trace[1038407935] 'range keys from in-memory index tree' (duration: 100.643662ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.412331Z","caller":"traceutil/trace.go:171","msg":"trace[286430240] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e8efe72-65a0-4497-9e15-3873268f8951; range_end:; response_count:1; response_revision:1055; }","duration":"168.299153ms","start":"2026-04-23T16:59:40.243522Z","end":"2026-04-23T16:59:40.411821Z","steps":["trace[286430240] 'range keys from in-memory index tree' (duration: 168.080849ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.609914Z","caller":"traceutil/trace.go:171","msg":"trace[1060818397] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"194.321361ms","start":"2026-04-23T16:59:40.415572Z","end":"2026-04-23T16:59:40.609893Z","steps":["trace[1060818397] 'process raft request' (duration: 194.21037ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.610161Z","caller":"traceutil/trace.go:171","msg":"trace[380558229] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"190.704573ms","start":"2026-04-23T16:59:40.41945Z","end":"2026-04-23T16:59:40.610154Z","steps":["trace[380558229] 'process raft request' (duration: 190.49996ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.609937Z","caller":"traceutil/trace.go:171","msg":"trace[1085162219] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"194.545656ms","start":"2026-04-23T16:59:40.41536Z","end":"2026-04-23T16:59:40.609906Z","steps":["trace[1085162219] 'process raft request' (duration: 166.620966ms)","trace[1085162219] 'compare' (duration: 27.679486ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:40.610007Z","caller":"traceutil/trace.go:171","msg":"trace[1659978557] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"193.60487ms","start":"2026-04-23T16:59:40.416387Z","end":"2026-04-23T16:59:40.609992Z","steps":["trace[1659978557] 'process raft request' (duration: 193.474568ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.79588Z","caller":"traceutil/trace.go:171","msg":"trace[1100933712] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"345.794442ms","start":"2026-04-23T16:59:40.450062Z","end":"2026-04-23T16:59:40.795856Z","steps":["trace[1100933712] 'process raft request' (duration: 327.409097ms)","trace[1100933712] 'compare' (duration: 18.208033ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:40.796037Z","caller":"traceutil/trace.go:171","msg":"trace[74649061] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"310.899841ms","start":"2026-04-23T16:59:40.485102Z","end":"2026-04-23T16:59:40.796002Z","steps":["trace[74649061] 'process raft request' (duration: 310.676087ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:40.796081Z","caller":"traceutil/trace.go:171","msg":"trace[1517579870] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"242.722041ms","start":"2026-04-23T16:59:40.55331Z","end":"2026-04-23T16:59:40.796032Z","steps":["trace[1517579870] 'process raft request' (duration: 242.507408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:40.796206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:40.485085Z","time spent":"311.033133ms","remote":"127.0.0.1:48470","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-23T16:59:40.796093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:40.450044Z","time spent":"345.977926ms","remote":"127.0.0.1:48242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:45.080483Z","caller":"traceutil/trace.go:171","msg":"trace[208164111] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"247.098457ms","start":"2026-04-23T16:59:44.83336Z","end":"2026-04-23T16:59:45.080459Z","steps":["trace[208164111] 'read index received' (duration: 238.784016ms)","trace[208164111] 'applied index is now lower than readState.Index' (duration: 8.313271ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:45.080558Z","caller":"traceutil/trace.go:171","msg":"trace[37136059] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"253.56936ms","start":"2026-04-23T16:59:44.826934Z","end":"2026-04-23T16:59:45.080504Z","steps":["trace[37136059] 'process raft request' (duration: 245.212198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:45.08092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.325751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T16:59:45.081311Z","caller":"traceutil/trace.go:171","msg":"trace[1657141443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"247.678806ms","start":"2026-04-23T16:59:44.833335Z","end":"2026-04-23T16:59:45.081014Z","steps":["trace[1657141443] 'agreement among raft nodes before linearized reading' (duration: 247.227719ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:46.840849Z","caller":"traceutil/trace.go:171","msg":"trace[1800588579] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"204.704752ms","start":"2026-04-23T16:59:46.636121Z","end":"2026-04-23T16:59:46.840826Z","steps":["trace[1800588579] 'process raft request' (duration: 204.55628ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:46.991185Z","caller":"traceutil/trace.go:171","msg":"trace[810118733] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"351.943704ms","start":"2026-04-23T16:59:46.639208Z","end":"2026-04-23T16:59:46.991152Z","steps":["trace[810118733] 'process raft request' (duration: 311.968597ms)","trace[810118733] 'compare' (duration: 39.770834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:46.991345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:46.639185Z","time spent":"352.069827ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:46.991568Z","caller":"traceutil/trace.go:171","msg":"trace[1880999433] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"352.155528ms","start":"2026-04-23T16:59:46.639384Z","end":"2026-04-23T16:59:46.99154Z","steps":["trace[1880999433] 'process raft request' (duration: 351.749142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:46.991722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:46.639372Z","time spent":"352.26838ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:59:46.992067Z","caller":"traceutil/trace.go:171","msg":"trace[237187074] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1128; }","duration":"279.137791ms","start":"2026-04-23T16:59:46.712911Z","end":"2026-04-23T16:59:46.992049Z","steps":["trace[237187074] 'read index received' (duration: 127.785595ms)","trace[237187074] 'applied index is now lower than readState.Index' (duration: 151.350936ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:46.992269Z","caller":"traceutil/trace.go:171","msg":"trace[1430530448] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"166.070736ms","start":"2026-04-23T16:59:46.826111Z","end":"2026-04-23T16:59:46.992181Z","steps":["trace[1430530448] 'process raft request' (duration: 165.186743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:46.992357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.425376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T16:59:46.992511Z","caller":"traceutil/trace.go:171","msg":"trace[118410311] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1099; }","duration":"279.610178ms","start":"2026-04-23T16:59:46.712881Z","end":"2026-04-23T16:59:46.992492Z","steps":["trace[118410311] 'agreement among raft nodes before linearized reading' (duration: 279.242372ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:49.126064Z","caller":"traceutil/trace.go:171","msg":"trace[1342688993] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"273.928936ms","start":"2026-04-23T16:59:48.85211Z","end":"2026-04-23T16:59:49.126039Z","steps":["trace[1342688993] 'process raft request' (duration: 273.763283ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:49.126095Z","caller":"traceutil/trace.go:171","msg":"trace[369580777] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1135; }","duration":"130.552865ms","start":"2026-04-23T16:59:48.995503Z","end":"2026-04-23T16:59:49.126056Z","steps":["trace[369580777] 'read index received' (duration: 130.518825ms)","trace[369580777] 'applied index is now lower than readState.Index' (duration: 32.05µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:49.126346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.824189ms","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-23T16:59:49.126396Z","caller":"traceutil/trace.go:171","msg":"trace[259549345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"130.927041ms","start":"2026-04-23T16:59:48.995459Z","end":"2026-04-23T16:59:49.126386Z","steps":["trace[259549345] 'agreement among raft nodes before linearized reading' (duration: 130.697568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:49.127553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.955218ms","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-23T16:59:49.127624Z","caller":"traceutil/trace.go:171","msg":"trace[89209501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"132.015578ms","start":"2026-04-23T16:59:48.995583Z","end":"2026-04-23T16:59:49.127598Z","steps":["trace[89209501] 'agreement among raft nodes before linearized reading' (duration: 131.875115ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:49.127685Z","caller":"traceutil/trace.go:171","msg":"trace[614806467] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"130.859869ms","start":"2026-04-23T16:59:48.996803Z","end":"2026-04-23T16:59:49.127663Z","steps":["trace[614806467] 'process raft request' (duration: 130.541934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:55.649131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371084964962144,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T16:59:55.864022Z","caller":"traceutil/trace.go:171","msg":"trace[826442513] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"716.624886ms","start":"2026-04-23T16:59:55.147368Z","end":"2026-04-23T16:59:55.863993Z","steps":["trace[826442513] 'process raft request' (duration: 716.443203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:55.864344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.147336Z","time spent":"716.762978ms","remote":"127.0.0.1:48470","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-23T16:59:56.540167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.528166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T16:59:56.540342Z","caller":"traceutil/trace.go:171","msg":"trace[456568925] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1162; }","duration":"1.391448947s","start":"2026-04-23T16:59:55.148879Z","end":"2026-04-23T16:59:56.540328Z","steps":["trace[456568925] 'read index received' (duration: 715.017241ms)","trace[456568925] 'applied index is now lower than readState.Index' (duration: 676.430356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:59:56.54051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386464951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-23T16:59:56.540524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391635459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-23T16:59:56.540563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.33587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:56.540568Z","caller":"traceutil/trace.go:171","msg":"trace[184752120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1129; }","duration":"1.386548702s","start":"2026-04-23T16:59:55.15401Z","end":"2026-04-23T16:59:56.540559Z","steps":["trace[184752120] 'agreement among raft nodes before linearized reading' (duration: 1.38642538s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:56.540604Z","caller":"traceutil/trace.go:171","msg":"trace[102378917] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1129; }","duration":"913.400791ms","start":"2026-04-23T16:59:55.627191Z","end":"2026-04-23T16:59:56.540592Z","steps":["trace[102378917] 'agreement among raft nodes before linearized reading' (duration: 913.349651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.540604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.930635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:59:56.540657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.627167Z","time spent":"913.480702ms","remote":"127.0.0.1:48360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-23T16:59:56.540595Z","caller":"traceutil/trace.go:171","msg":"trace[1306986573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1129; }","duration":"1.3917412s","start":"2026-04-23T16:59:55.148839Z","end":"2026-04-23T16:59:56.54058Z","steps":["trace[1306986573] 'agreement among raft nodes before linearized reading' (duration: 1.391547997s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.540694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.153998Z","time spent":"1.386660443s","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T16:59:56.54076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.428205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:56.540797Z","caller":"traceutil/trace.go:171","msg":"trace[345847971] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1129; }","duration":"250.503556ms","start":"2026-04-23T16:59:56.290285Z","end":"2026-04-23T16:59:56.540788Z","steps":["trace[345847971] 'agreement among raft nodes before linearized reading' (duration: 250.445636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.540757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360271886s","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-23T16:59:56.540828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.888598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T16:59:56.54088Z","caller":"traceutil/trace.go:171","msg":"trace[588038285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"1.360480218s","start":"2026-04-23T16:59:55.180377Z","end":"2026-04-23T16:59:56.540858Z","steps":["trace[588038285] 'agreement among raft nodes before linearized reading' (duration: 1.360104723s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.54078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.148821Z","time spent":"1.391900623s","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T16:59:56.541002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380088734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-23T16:59:56.541034Z","caller":"traceutil/trace.go:171","msg":"trace[592714201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1129; }","duration":"1.380145965s","start":"2026-04-23T16:59:55.160879Z","end":"2026-04-23T16:59:56.541024Z","steps":["trace[592714201] 'agreement among raft nodes before linearized reading' (duration: 1.380055444s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.541073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.160865Z","time spent":"1.380200936s","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:59:56.540513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252980942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:59:56.540688Z","caller":"traceutil/trace.go:171","msg":"trace[503000976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"230.060287ms","start":"2026-04-23T16:59:56.31061Z","end":"2026-04-23T16:59:56.54067Z","steps":["trace[503000976] 'agreement among raft nodes before linearized reading' (duration: 229.924965ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:56.541128Z","caller":"traceutil/trace.go:171","msg":"trace[1441847466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1129; }","duration":"1.253603701s","start":"2026-04-23T16:59:55.287511Z","end":"2026-04-23T16:59:56.541114Z","steps":["trace[1441847466] 'agreement among raft nodes before linearized reading' (duration: 1.252952502s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.541174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.287443Z","time spent":"1.253714363s","remote":"127.0.0.1:35826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T16:59:56.541004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.18036Z","time spent":"1.36062539s","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T16:59:56.540948Z","caller":"traceutil/trace.go:171","msg":"trace[2145857878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1129; }","duration":"827.986509ms","start":"2026-04-23T16:59:55.712897Z","end":"2026-04-23T16:59:56.540883Z","steps":["trace[2145857878] 'agreement among raft nodes before linearized reading' (duration: 827.546352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:59:56.541302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:59:55.712879Z","time spent":"828.383635ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T16:59:56.767412Z","caller":"traceutil/trace.go:171","msg":"trace[1627203601] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"220.80632ms","start":"2026-04-23T16:59:56.546583Z","end":"2026-04-23T16:59:56.76739Z","steps":["trace[1627203601] 'process raft request' (duration: 124.647269ms)","trace[1627203601] 'compare' (duration: 96.016288ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:59:56.767684Z","caller":"traceutil/trace.go:171","msg":"trace[1101970135] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"220.928251ms","start":"2026-04-23T16:59:56.54673Z","end":"2026-04-23T16:59:56.767659Z","steps":["trace[1101970135] 'process raft request' (duration: 220.631137ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:56.767827Z","caller":"traceutil/trace.go:171","msg":"trace[1453779587] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"221.086534ms","start":"2026-04-23T16:59:56.546732Z","end":"2026-04-23T16:59:56.767818Z","steps":["trace[1453779587] 'process raft request' (duration: 220.84983ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:56.767902Z","caller":"traceutil/trace.go:171","msg":"trace[4637152] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"221.107504ms","start":"2026-04-23T16:59:56.546781Z","end":"2026-04-23T16:59:56.767889Z","steps":["trace[4637152] 'process raft request' (duration: 221.008842ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:58.901113Z","caller":"traceutil/trace.go:171","msg":"trace[915724094] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"127.089471ms","start":"2026-04-23T16:59:58.773998Z","end":"2026-04-23T16:59:58.901088Z","steps":["trace[915724094] 'process raft request' (duration: 126.930589ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:58.941673Z","caller":"traceutil/trace.go:171","msg":"trace[523377975] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"165.820251ms","start":"2026-04-23T16:59:58.775836Z","end":"2026-04-23T16:59:58.941656Z","steps":["trace[523377975] 'process raft request' (duration: 165.79273ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:58.941887Z","caller":"traceutil/trace.go:171","msg":"trace[1572690112] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"166.328618ms","start":"2026-04-23T16:59:58.775551Z","end":"2026-04-23T16:59:58.941879Z","steps":["trace[1572690112] 'process raft request' (duration: 166.045574ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:59:58.941936Z","caller":"traceutil/trace.go:171","msg":"trace[986690433] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"167.398524ms","start":"2026-04-23T16:59:58.774508Z","end":"2026-04-23T16:59:58.941907Z","steps":["trace[986690433] 'process raft request' (duration: 164.041294ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:05.113418Z","caller":"traceutil/trace.go:171","msg":"trace[1660635497] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"145.180231ms","start":"2026-04-23T17:00:04.968216Z","end":"2026-04-23T17:00:05.113396Z","steps":["trace[1660635497] 'process raft request' (duration: 105.088206ms)","trace[1660635497] 'compare' (duration: 39.966704ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:00:05.113699Z","caller":"traceutil/trace.go:171","msg":"trace[1366978403] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"145.364194ms","start":"2026-04-23T17:00:04.968292Z","end":"2026-04-23T17:00:05.113656Z","steps":["trace[1366978403] 'process raft request' (duration: 145.07053ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:05.113713Z","caller":"traceutil/trace.go:171","msg":"trace[1812873528] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1198; }","duration":"140.630355ms","start":"2026-04-23T17:00:04.973057Z","end":"2026-04-23T17:00:05.113688Z","steps":["trace[1812873528] 'read index received' (duration: 39.178324ms)","trace[1812873528] 'applied index is now lower than readState.Index' (duration: 101.450821ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:00:05.113847Z","caller":"traceutil/trace.go:171","msg":"trace[348571613] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"145.369645ms","start":"2026-04-23T17:00:04.968466Z","end":"2026-04-23T17:00:05.113836Z","steps":["trace[348571613] 'process raft request' (duration: 145.119351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:00:05.113908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.697705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:915"} {"level":"info","ts":"2026-04-23T17:00:05.113959Z","caller":"traceutil/trace.go:171","msg":"trace[2066180454] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1165; }","duration":"140.764396ms","start":"2026-04-23T17:00:04.973179Z","end":"2026-04-23T17:00:05.113944Z","steps":["trace[2066180454] 'agreement among raft nodes before linearized reading' (duration: 140.679705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:00:05.113945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.861708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:974"} {"level":"info","ts":"2026-04-23T17:00:05.114021Z","caller":"traceutil/trace.go:171","msg":"trace[957794922] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1165; }","duration":"140.958059ms","start":"2026-04-23T17:00:04.973042Z","end":"2026-04-23T17:00:05.114Z","steps":["trace[957794922] 'agreement among raft nodes before linearized reading' (duration: 140.831987ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:09.337336Z","caller":"traceutil/trace.go:171","msg":"trace[1049755038] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"129.312593ms","start":"2026-04-23T17:00:09.207994Z","end":"2026-04-23T17:00:09.337307Z","steps":["trace[1049755038] 'process raft request' (duration: 126.068386ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:09.337532Z","caller":"traceutil/trace.go:171","msg":"trace[904620349] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"129.425364ms","start":"2026-04-23T17:00:09.208077Z","end":"2026-04-23T17:00:09.337502Z","steps":["trace[904620349] 'process raft request' (duration: 129.191701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:00:12.237387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.882259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"warn","ts":"2026-04-23T17:00:12.237396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.436474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-04-23T17:00:12.237468Z","caller":"traceutil/trace.go:171","msg":"trace[951973815] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1186; }","duration":"147.997881ms","start":"2026-04-23T17:00:12.08945Z","end":"2026-04-23T17:00:12.237448Z","steps":["trace[951973815] 'range keys from in-memory index tree' (duration: 147.824259ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:00:12.237479Z","caller":"traceutil/trace.go:171","msg":"trace[2087569937] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1186; }","duration":"145.545507ms","start":"2026-04-23T17:00:12.091913Z","end":"2026-04-23T17:00:12.237458Z","steps":["trace[2087569937] 'range keys from in-memory index tree' (duration: 145.371614ms)"],"step_count":1}