{"level":"warn","ts":"2026-06-05T15:55:40.717738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T15:55:40.717933Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.67: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.67:2380","--initial-cluster=instance=https://199.19.213.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.67: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-06-05T15:55:40.71805Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T15:55:40.718074Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-06-05T15:55:40.718111Z","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-06-05T15:55:40.719259Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"]} {"level":"info","ts":"2026-06-05T15:55:40.719361Z","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.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.67: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-06-05T15:55:40.721603Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.987335ms"} {"level":"info","ts":"2026-06-05T15:55:40.725657Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fba6d6d8cd5f215c","cluster-id":"f3c660457a1c2c72"} {"level":"info","ts":"2026-06-05T15:55:40.72573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T15:55:40.725753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 0"} {"level":"info","ts":"2026-06-05T15:55:40.725793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fba6d6d8cd5f215c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T15:55:40.725803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 1"} {"level":"info","ts":"2026-06-05T15:55:40.725838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"warn","ts":"2026-06-05T15:55:40.732571Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T15:55:40.733713Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T15:55:40.734647Z","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-06-05T15:55:40.736527Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fba6d6d8cd5f215c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T15:55:40.736961Z","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-06-05T15:55:40.737019Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fba6d6d8cd5f215c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T15:55:40.737033Z","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-06-05T15:55:40.742659Z","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-06-05T15:55:40.743122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"info","ts":"2026-06-05T15:55:40.743387Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","added-peer-id":"fba6d6d8cd5f215c","added-peer-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-06-05T15:55:40.74559Z","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-06-05T15:55:40.745856Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fba6d6d8cd5f215c","initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T15:55:40.745912Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T15:55:40.745848Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-06-05T15:55:40.745992Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-06-05T15:55:40.826742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T15:55:40.826787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T15:55:40.826814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgPreVoteResp from fba6d6d8cd5f215c at term 1"} {"level":"info","ts":"2026-06-05T15:55:40.826828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became candidate at term 2"} {"level":"info","ts":"2026-06-05T15:55:40.826929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgVoteResp from fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-06-05T15:55:40.826944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became leader at term 2"} {"level":"info","ts":"2026-06-05T15:55:40.826965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fba6d6d8cd5f215c elected leader fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-06-05T15:55:40.828024Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T15:55:40.828836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T15:55:40.828864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T15:55:40.829064Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T15:55:40.829155Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T15:55:40.829143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T15:55:40.829202Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T15:55:40.829202Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T15:55:40.830119Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fba6d6d8cd5f215c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.67:2379]}","request-path":"/0/members/fba6d6d8cd5f215c/attributes","cluster-id":"f3c660457a1c2c72","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T15:55:40.831307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T15:55:40.832318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.67:2379"} {"level":"warn","ts":"2026-06-05T15:55:43.877169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917117407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T15:55:43.937537Z","caller":"traceutil/trace.go:171","msg":"trace[47296918] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"561.950407ms","start":"2026-06-05T15:55:43.375543Z","end":"2026-06-05T15:55:43.937494Z","steps":["trace[47296918] 'process raft request' (duration: 561.663779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:55:43.938424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:55:43.375531Z","time spent":"562.289286ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T15:55:43.947603Z","caller":"traceutil/trace.go:171","msg":"trace[2015378804] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:74; }","duration":"570.457613ms","start":"2026-06-05T15:55:43.37711Z","end":"2026-06-05T15:55:43.947568Z","steps":["trace[2015378804] 'read index received' (duration: 560.087594ms)","trace[2015378804] 'applied index is now lower than readState.Index' (duration: 10.369009ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:55:43.948027Z","caller":"traceutil/trace.go:171","msg":"trace[669200159] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"260.306123ms","start":"2026-06-05T15:55:43.687701Z","end":"2026-06-05T15:55:43.948008Z","steps":["trace[669200159] 'process raft request' (duration: 259.685116ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:55:43.948124Z","caller":"traceutil/trace.go:171","msg":"trace[463704013] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"240.590124ms","start":"2026-06-05T15:55:43.707446Z","end":"2026-06-05T15:55:43.948036Z","steps":["trace[463704013] 'process raft request' (duration: 240.07119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:55:43.948275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.883083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T15:55:43.948346Z","caller":"traceutil/trace.go:171","msg":"trace[943195013] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:73; }","duration":"370.012926ms","start":"2026-06-05T15:55:43.578308Z","end":"2026-06-05T15:55:43.948321Z","steps":["trace[943195013] 'agreement among raft nodes before linearized reading' (duration: 369.902113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:55:43.948387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.809011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-05T15:55:43.948405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:55:43.578266Z","time spent":"370.128009ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-06-05T15:55:43.948148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.900885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T15:55:43.948493Z","caller":"traceutil/trace.go:171","msg":"trace[1866952040] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:73; }","duration":"365.161061ms","start":"2026-06-05T15:55:43.583293Z","end":"2026-06-05T15:55:43.948454Z","steps":["trace[1866952040] 'agreement among raft nodes before linearized reading' (duration: 364.76493ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:55:43.948528Z","caller":"traceutil/trace.go:171","msg":"trace[1477000763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"470.331728ms","start":"2026-06-05T15:55:43.478151Z","end":"2026-06-05T15:55:43.948483Z","steps":["trace[1477000763] 'agreement among raft nodes before linearized reading' (duration: 469.883865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:55:43.948557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:55:43.58328Z","time spent":"365.261973ms","remote":"127.0.0.1:37804","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-06-05T15:55:43.948622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:55:43.478129Z","time spent":"470.466371ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T15:55:43.948062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.940416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-05T15:55:43.949867Z","caller":"traceutil/trace.go:171","msg":"trace[315559545] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:73; }","duration":"572.779047ms","start":"2026-06-05T15:55:43.377058Z","end":"2026-06-05T15:55:43.949837Z","steps":["trace[315559545] 'agreement among raft nodes before linearized reading' (duration: 570.821553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:55:43.949934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:55:43.377037Z","time spent":"572.880471ms","remote":"127.0.0.1:37744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-06-05T15:56:05.638736Z","caller":"traceutil/trace.go:171","msg":"trace[1546768786] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"122.522101ms","start":"2026-06-05T15:56:05.516193Z","end":"2026-06-05T15:56:05.638715Z","steps":["trace[1546768786] 'process raft request' (duration: 122.379954ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:14.978591Z","caller":"traceutil/trace.go:171","msg":"trace[1761885822] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"292.380607ms","start":"2026-06-05T15:56:14.686183Z","end":"2026-06-05T15:56:14.978563Z","steps":["trace[1761885822] 'process raft request' (duration: 292.188026ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:14.979005Z","caller":"traceutil/trace.go:171","msg":"trace[1933267228] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"287.346502ms","start":"2026-06-05T15:56:14.69163Z","end":"2026-06-05T15:56:14.978977Z","steps":["trace[1933267228] 'process raft request' (duration: 286.886486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.432949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917118374,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T15:56:17.650237Z","caller":"traceutil/trace.go:171","msg":"trace[1848691741] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"718.459909ms","start":"2026-06-05T15:56:16.93175Z","end":"2026-06-05T15:56:17.650209Z","steps":["trace[1848691741] 'process raft request' (duration: 712.409697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.650422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.931737Z","time spent":"718.629758ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T15:56:17.650494Z","caller":"traceutil/trace.go:171","msg":"trace[680376909] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"693.795858ms","start":"2026-06-05T15:56:16.956656Z","end":"2026-06-05T15:56:17.650451Z","steps":["trace[680376909] 'process raft request' (duration: 693.518653ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:17.650563Z","caller":"traceutil/trace.go:171","msg":"trace[1909435472] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"686.404422ms","start":"2026-06-05T15:56:16.96413Z","end":"2026-06-05T15:56:17.650535Z","steps":["trace[1909435472] 'process raft request' (duration: 686.245354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.651672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.956634Z","time spent":"693.946836ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T15:56:17.651901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.964115Z","time spent":"686.496768ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T15:56:17.652353Z","caller":"traceutil/trace.go:171","msg":"trace[961017920] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"659.442256ms","start":"2026-06-05T15:56:16.992887Z","end":"2026-06-05T15:56:17.652329Z","steps":["trace[961017920] 'process raft request' (duration: 657.563863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.652448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.99287Z","time spent":"659.527591ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T15:56:17.654976Z","caller":"traceutil/trace.go:171","msg":"trace[516587444] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"615.213103ms","start":"2026-06-05T15:56:17.039733Z","end":"2026-06-05T15:56:17.654946Z","steps":["trace[516587444] 'process raft request' (duration: 612.351826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.655085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:17.039704Z","time spent":"615.325469ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T15:56:17.655776Z","caller":"traceutil/trace.go:171","msg":"trace[1761553422] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:576; }","duration":"723.625761ms","start":"2026-06-05T15:56:16.932127Z","end":"2026-06-05T15:56:17.655753Z","steps":["trace[1761553422] 'read index received' (duration: 712.018695ms)","trace[1761553422] 'applied index is now lower than readState.Index' (duration: 11.605286ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:56:17.655949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.804892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:56:17.65599Z","caller":"traceutil/trace.go:171","msg":"trace[1593003815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"723.866705ms","start":"2026-06-05T15:56:16.932113Z","end":"2026-06-05T15:56:17.65598Z","steps":["trace[1593003815] 'agreement among raft nodes before linearized reading' (duration: 723.757359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.656026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.932107Z","time spent":"723.912008ms","remote":"127.0.0.1:37398","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-06-05T15:56:17.656245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.460484ms","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-06-05T15:56:17.656282Z","caller":"traceutil/trace.go:171","msg":"trace[1464293484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"671.524558ms","start":"2026-06-05T15:56:16.984749Z","end":"2026-06-05T15:56:17.656274Z","steps":["trace[1464293484] 'agreement among raft nodes before linearized reading' (duration: 671.452494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.656311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.984738Z","time spent":"671.566411ms","remote":"127.0.0.1:37660","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-06-05T15:56:17.656503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.863577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-05T15:56:17.656566Z","caller":"traceutil/trace.go:171","msg":"trace[1789706113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"665.944261ms","start":"2026-06-05T15:56:16.990613Z","end":"2026-06-05T15:56:17.656557Z","steps":["trace[1789706113] 'agreement among raft nodes before linearized reading' (duration: 665.858437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.6566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.990604Z","time spent":"665.989214ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T15:56:17.656855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.93472ms","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-06-05T15:56:17.656957Z","caller":"traceutil/trace.go:171","msg":"trace[88083029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"672.085839ms","start":"2026-06-05T15:56:16.98486Z","end":"2026-06-05T15:56:17.656945Z","steps":["trace[88083029] 'agreement among raft nodes before linearized reading' (duration: 671.939321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:17.65705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:16.98483Z","time spent":"672.210365ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T15:56:18.19047Z","caller":"traceutil/trace.go:171","msg":"trace[1520627547] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"116.579097ms","start":"2026-06-05T15:56:18.073862Z","end":"2026-06-05T15:56:18.190441Z","steps":["trace[1520627547] 'process raft request' (duration: 35.142895ms)","trace[1520627547] 'compare' (duration: 81.290714ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:56:24.431026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917118584,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T15:56:24.496403Z","caller":"traceutil/trace.go:171","msg":"trace[1238792731] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"565.857841ms","start":"2026-06-05T15:56:23.93046Z","end":"2026-06-05T15:56:24.496318Z","steps":["trace[1238792731] 'read index received' (duration: 557.708408ms)","trace[1238792731] 'applied index is now lower than readState.Index' (duration: 8.147793ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:56:24.496629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.146158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:56:24.496679Z","caller":"traceutil/trace.go:171","msg":"trace[1697624878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"566.20586ms","start":"2026-06-05T15:56:23.930445Z","end":"2026-06-05T15:56:24.496651Z","steps":["trace[1697624878] 'agreement among raft nodes before linearized reading' (duration: 566.067136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:24.496711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:23.930392Z","time spent":"566.312504ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T15:56:24.496897Z","caller":"traceutil/trace.go:171","msg":"trace[107658253] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"657.633932ms","start":"2026-06-05T15:56:23.83922Z","end":"2026-06-05T15:56:24.496854Z","steps":["trace[107658253] 'process raft request' (duration: 649.337835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:24.497716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:23.83919Z","time spent":"658.351101ms","remote":"127.0.0.1:37488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T15:56:25.18192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917118587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T15:56:25.205643Z","caller":"traceutil/trace.go:171","msg":"trace[507019011] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"524.598762ms","start":"2026-06-05T15:56:24.68102Z","end":"2026-06-05T15:56:25.205619Z","steps":["trace[507019011] 'read index received' (duration: 524.405646ms)","trace[507019011] 'applied index is now lower than readState.Index' (duration: 192.516µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:56:25.205734Z","caller":"traceutil/trace.go:171","msg":"trace[1574866189] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"655.527294ms","start":"2026-06-05T15:56:24.550199Z","end":"2026-06-05T15:56:25.205726Z","steps":["trace[1574866189] 'process raft request' (duration: 655.311348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:25.205799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:24.549619Z","time spent":"656.13356ms","remote":"127.0.0.1:37642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T15:56:25.205879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.306057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:56:25.205917Z","caller":"traceutil/trace.go:171","msg":"trace[1053170283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"277.384369ms","start":"2026-06-05T15:56:24.928524Z","end":"2026-06-05T15:56:25.205908Z","steps":["trace[1053170283] 'agreement among raft nodes before linearized reading' (duration: 277.303936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:25.205988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.965041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T15:56:25.206069Z","caller":"traceutil/trace.go:171","msg":"trace[1394562831] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:638; }","duration":"525.090425ms","start":"2026-06-05T15:56:24.680956Z","end":"2026-06-05T15:56:25.206046Z","steps":["trace[1394562831] 'agreement among raft nodes before linearized reading' (duration: 524.917751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:25.206123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:24.680921Z","time spent":"525.190018ms","remote":"127.0.0.1:37414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.67\" "} {"level":"warn","ts":"2026-06-05T15:56:26.209192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917118600,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T15:56:26.916734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:25.892302Z","time spent":"1.024423635s","remote":"127.0.0.1:59184","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-05T15:56:26.916999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.994631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T15:56:26.917078Z","caller":"traceutil/trace.go:171","msg":"trace[529643670] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"1.20812752s","start":"2026-06-05T15:56:25.708936Z","end":"2026-06-05T15:56:26.917064Z","steps":["trace[529643670] 'read index received' (duration: 549.837831ms)","trace[529643670] 'applied index is now lower than readState.Index' (duration: 658.288639ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:56:26.917233Z","caller":"traceutil/trace.go:171","msg":"trace[1935155213] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.653898945s","start":"2026-06-05T15:56:25.263323Z","end":"2026-06-05T15:56:26.917222Z","steps":["trace[1935155213] 'process raft request' (duration: 995.509953ms)","trace[1935155213] 'compare' (duration: 657.654812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:56:26.917279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:25.263314Z","time spent":"1.653944987s","remote":"127.0.0.1:37588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-05T15:56:26.917536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208594813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-05T15:56:26.917562Z","caller":"traceutil/trace.go:171","msg":"trace[2006246776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.208641024s","start":"2026-06-05T15:56:25.708913Z","end":"2026-06-05T15:56:26.917554Z","steps":["trace[2006246776] 'agreement among raft nodes before linearized reading' (duration: 1.20850251s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:26.917588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:25.708906Z","time spent":"1.208676685s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T15:56:26.91779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208807019s","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-06-05T15:56:26.917856Z","caller":"traceutil/trace.go:171","msg":"trace[1492682227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.208919912s","start":"2026-06-05T15:56:25.708917Z","end":"2026-06-05T15:56:26.917837Z","steps":["trace[1492682227] 'agreement among raft nodes before linearized reading' (duration: 1.208707036s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:26.917904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:25.708906Z","time spent":"1.208988404s","remote":"127.0.0.1:37660","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-06-05T15:56:26.918136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180136674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T15:56:26.918166Z","caller":"traceutil/trace.go:171","msg":"trace[1269610219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.180205847s","start":"2026-06-05T15:56:25.737952Z","end":"2026-06-05T15:56:26.918158Z","steps":["trace[1269610219] 'agreement among raft nodes before linearized reading' (duration: 1.180121304s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:26.918193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:25.737929Z","time spent":"1.180257298s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T15:56:26.918194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209218371s","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-06-05T15:56:26.918229Z","caller":"traceutil/trace.go:171","msg":"trace[977291513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.209376394s","start":"2026-06-05T15:56:25.708843Z","end":"2026-06-05T15:56:26.918219Z","steps":["trace[977291513] 'agreement among raft nodes before linearized reading' (duration: 1.209282952s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:26.918255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:25.708788Z","time spent":"1.209461966s","remote":"127.0.0.1:37660","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-06-05T15:56:28.079309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.657332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:56:28.079456Z","caller":"traceutil/trace.go:171","msg":"trace[1940976625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"150.895038ms","start":"2026-06-05T15:56:27.92853Z","end":"2026-06-05T15:56:28.079425Z","steps":["trace[1940976625] 'range keys from in-memory index tree' (duration: 150.575239ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:31.849603Z","caller":"traceutil/trace.go:171","msg":"trace[1495733528] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"120.011183ms","start":"2026-06-05T15:56:31.729572Z","end":"2026-06-05T15:56:31.849583Z","steps":["trace[1495733528] 'process raft request' (duration: 119.90497ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:31.88643Z","caller":"traceutil/trace.go:171","msg":"trace[1738818401] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"118.280186ms","start":"2026-06-05T15:56:31.768133Z","end":"2026-06-05T15:56:31.886413Z","steps":["trace[1738818401] 'process raft request' (duration: 118.225224ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:31.886495Z","caller":"traceutil/trace.go:171","msg":"trace[2026222921] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"143.537197ms","start":"2026-06-05T15:56:31.74293Z","end":"2026-06-05T15:56:31.886467Z","steps":["trace[2026222921] 'process raft request' (duration: 143.301511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:42.639236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.739452ms","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-06-05T15:56:42.639355Z","caller":"traceutil/trace.go:171","msg":"trace[175963053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"230.936488ms","start":"2026-06-05T15:56:42.408398Z","end":"2026-06-05T15:56:42.639335Z","steps":["trace[175963053] 'range keys from in-memory index tree' (duration: 230.448075ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:42.95941Z","caller":"traceutil/trace.go:171","msg":"trace[848205068] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"315.82825ms","start":"2026-06-05T15:56:42.643562Z","end":"2026-06-05T15:56:42.959391Z","steps":["trace[848205068] 'process raft request' (duration: 315.674866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:56:42.959567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:56:42.643541Z","time spent":"315.929894ms","remote":"127.0.0.1:37660","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-06-05T15:56:43.085265Z","caller":"traceutil/trace.go:171","msg":"trace[218121220] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"156.387608ms","start":"2026-06-05T15:56:42.928858Z","end":"2026-06-05T15:56:43.085246Z","steps":["trace[218121220] 'read index received' (duration: 30.405302ms)","trace[218121220] 'applied index is now lower than readState.Index' (duration: 125.981256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:56:43.085758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.854411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:56:43.085798Z","caller":"traceutil/trace.go:171","msg":"trace[1521013866] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"281.560042ms","start":"2026-06-05T15:56:42.804194Z","end":"2026-06-05T15:56:43.085754Z","steps":["trace[1521013866] 'process raft request' (duration: 280.910234ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:56:43.085877Z","caller":"traceutil/trace.go:171","msg":"trace[1945423660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"157.009985ms","start":"2026-06-05T15:56:42.928827Z","end":"2026-06-05T15:56:43.085837Z","steps":["trace[1945423660] 'agreement among raft nodes before linearized reading' (duration: 156.780429ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:57:12.058294Z","caller":"traceutil/trace.go:171","msg":"trace[634649653] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"117.995648ms","start":"2026-06-05T15:57:11.940275Z","end":"2026-06-05T15:57:12.058271Z","steps":["trace[634649653] 'process raft request' (duration: 117.850073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:17.75875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917119384,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T15:57:18.259241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917119384,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T15:57:18.759374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917119384,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T15:57:18.763415Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.508552237s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T15:57:18.763744Z","caller":"traceutil/trace.go:171","msg":"trace[305925278] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.508953689s","start":"2026-06-05T15:57:17.25475Z","end":"2026-06-05T15:57:18.763704Z","steps":["trace[305925278] 'process raft request' (duration: 1.508797454s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:18.763884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:17.254724Z","time spent":"1.509088883s","remote":"127.0.0.1:37660","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-06-05T15:57:18.780275Z","caller":"traceutil/trace.go:171","msg":"trace[1737268407] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1056; }","duration":"1.522254864s","start":"2026-06-05T15:57:17.257996Z","end":"2026-06-05T15:57:18.780251Z","steps":["trace[1737268407] 'read index received' (duration: 1.505537273s)","trace[1737268407] 'applied index is now lower than readState.Index' (duration: 16.716621ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:57:18.780299Z","caller":"traceutil/trace.go:171","msg":"trace[575050406] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.524824964s","start":"2026-06-05T15:57:17.25545Z","end":"2026-06-05T15:57:18.780275Z","steps":["trace[575050406] 'process raft request' (duration: 1.524626677s)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:57:18.780386Z","caller":"traceutil/trace.go:171","msg":"trace[45546474] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"705.977773ms","start":"2026-06-05T15:57:18.074396Z","end":"2026-06-05T15:57:18.780374Z","steps":["trace[45546474] 'process raft request' (duration: 705.816858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:18.780442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:17.255431Z","time spent":"1.524939487s","remote":"127.0.0.1:37660","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-06-05T15:57:18.780511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:18.074369Z","time spent":"706.066885ms","remote":"127.0.0.1:37660","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-06-05T15:57:18.780665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521991385s","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-06-05T15:57:18.780722Z","caller":"traceutil/trace.go:171","msg":"trace[1841318686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.522067488s","start":"2026-06-05T15:57:17.258639Z","end":"2026-06-05T15:57:18.780706Z","steps":["trace[1841318686] 'agreement among raft nodes before linearized reading' (duration: 1.521904603s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:18.780754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:17.258628Z","time spent":"1.522118639s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T15:57:18.780774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52277905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T15:57:18.780799Z","caller":"traceutil/trace.go:171","msg":"trace[371704532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.522820221s","start":"2026-06-05T15:57:17.257969Z","end":"2026-06-05T15:57:18.780789Z","steps":["trace[371704532] 'agreement among raft nodes before linearized reading' (duration: 1.522730429s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:18.780821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:17.257958Z","time spent":"1.522859212s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T15:57:18.780681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.762608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T15:57:18.780859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441234128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T15:57:18.780877Z","caller":"traceutil/trace.go:171","msg":"trace[601785377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"852.987665ms","start":"2026-06-05T15:57:17.927883Z","end":"2026-06-05T15:57:18.780871Z","steps":["trace[601785377] 'agreement among raft nodes before linearized reading' (duration: 852.769258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:18.780894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:17.927867Z","time spent":"853.023946ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T15:57:18.78089Z","caller":"traceutil/trace.go:171","msg":"trace[941904514] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1020; }","duration":"1.44128985s","start":"2026-06-05T15:57:17.339592Z","end":"2026-06-05T15:57:18.780882Z","steps":["trace[941904514] 'agreement among raft nodes before linearized reading' (duration: 1.441240749s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:18.780917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:17.339581Z","time spent":"1.441329171s","remote":"127.0.0.1:37564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-05T15:57:19.255674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.615771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T15:57:19.255791Z","caller":"traceutil/trace.go:171","msg":"trace[1291825055] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1060; }","duration":"138.039522ms","start":"2026-06-05T15:57:19.117739Z","end":"2026-06-05T15:57:19.255778Z","steps":["trace[1291825055] 'read index received' (duration: 29.651µs)","trace[1291825055] 'applied index is now lower than readState.Index' (duration: 138.009201ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:57:19.255888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.153936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-05T15:57:19.255882Z","caller":"traceutil/trace.go:171","msg":"trace[1411234495] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"470.281447ms","start":"2026-06-05T15:57:18.785588Z","end":"2026-06-05T15:57:19.255869Z","steps":["trace[1411234495] 'process raft request' (duration: 99.398378ms)","trace[1411234495] 'compare' (duration: 370.544369ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:57:19.255905Z","caller":"traceutil/trace.go:171","msg":"trace[1410884129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"138.197467ms","start":"2026-06-05T15:57:19.117702Z","end":"2026-06-05T15:57:19.255899Z","steps":["trace[1410884129] 'agreement among raft nodes before linearized reading' (duration: 138.110014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:19.255936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:18.785572Z","time spent":"470.338389ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T15:57:19.256001Z","caller":"traceutil/trace.go:171","msg":"trace[1512717930] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"396.421555ms","start":"2026-06-05T15:57:18.859572Z","end":"2026-06-05T15:57:19.255994Z","steps":["trace[1512717930] 'process raft request' (duration: 396.173627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:19.256042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:18.859548Z","time spent":"396.466367ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T15:57:30.9613Z","caller":"traceutil/trace.go:171","msg":"trace[472712003] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"151.685548ms","start":"2026-06-05T15:57:30.809558Z","end":"2026-06-05T15:57:30.961244Z","steps":["trace[472712003] 'process raft request' (duration: 151.579675ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:57:30.962553Z","caller":"traceutil/trace.go:171","msg":"trace[2060995138] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"132.881992ms","start":"2026-06-05T15:57:30.829643Z","end":"2026-06-05T15:57:30.962525Z","steps":["trace[2060995138] 'read index received' (duration: 131.589372ms)","trace[2060995138] 'applied index is now lower than readState.Index' (duration: 1.2914ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:57:30.962594Z","caller":"traceutil/trace.go:171","msg":"trace[1188543942] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"140.817349ms","start":"2026-06-05T15:57:30.821749Z","end":"2026-06-05T15:57:30.962566Z","steps":["trace[1188543942] 'process raft request' (duration: 140.640664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:30.962781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.285448ms","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-06-05T15:57:30.962817Z","caller":"traceutil/trace.go:171","msg":"trace[991570062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"119.368731ms","start":"2026-06-05T15:57:30.843437Z","end":"2026-06-05T15:57:30.962806Z","steps":["trace[991570062] 'agreement among raft nodes before linearized reading' (duration: 119.218656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:30.962828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.162131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-05T15:57:30.962956Z","caller":"traceutil/trace.go:171","msg":"trace[918193848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"133.320806ms","start":"2026-06-05T15:57:30.829615Z","end":"2026-06-05T15:57:30.962936Z","steps":["trace[918193848] 'agreement among raft nodes before linearized reading' (duration: 133.037877ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:57:43.179411Z","caller":"traceutil/trace.go:171","msg":"trace[1040832711] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"170.422972ms","start":"2026-06-05T15:57:43.008961Z","end":"2026-06-05T15:57:43.179384Z","steps":["trace[1040832711] 'read index received' (duration: 170.155243ms)","trace[1040832711] 'applied index is now lower than readState.Index' (duration: 266.689µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:57:43.179517Z","caller":"traceutil/trace.go:171","msg":"trace[1456956425] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"177.602545ms","start":"2026-06-05T15:57:43.001881Z","end":"2026-06-05T15:57:43.179484Z","steps":["trace[1456956425] 'process raft request' (duration: 177.340007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:43.179712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.732781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T15:57:43.17976Z","caller":"traceutil/trace.go:171","msg":"trace[1036376007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"170.834214ms","start":"2026-06-05T15:57:43.008914Z","end":"2026-06-05T15:57:43.179748Z","steps":["trace[1036376007] 'agreement among raft nodes before linearized reading' (duration: 170.649578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:43.17982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.661518ms","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-06-05T15:57:43.17991Z","caller":"traceutil/trace.go:171","msg":"trace[1518263795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"160.777461ms","start":"2026-06-05T15:57:43.019119Z","end":"2026-06-05T15:57:43.179896Z","steps":["trace[1518263795] 'agreement among raft nodes before linearized reading' (duration: 160.581615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:43.179898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.409804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-05T15:57:43.179969Z","caller":"traceutil/trace.go:171","msg":"trace[357767099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"164.534607ms","start":"2026-06-05T15:57:43.015417Z","end":"2026-06-05T15:57:43.179952Z","steps":["trace[357767099] 'agreement among raft nodes before linearized reading' (duration: 164.254649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:43.877958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403970678917119600,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T15:57:44.152012Z","caller":"traceutil/trace.go:171","msg":"trace[29584486] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"967.804894ms","start":"2026-06-05T15:57:43.184185Z","end":"2026-06-05T15:57:44.15199Z","steps":["trace[29584486] 'process raft request' (duration: 967.68341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:44.152145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:43.184162Z","time spent":"967.915087ms","remote":"127.0.0.1:37660","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-06-05T15:57:44.16333Z","caller":"traceutil/trace.go:171","msg":"trace[1549439980] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"978.540128ms","start":"2026-06-05T15:57:43.184766Z","end":"2026-06-05T15:57:44.163306Z","steps":["trace[1549439980] 'process raft request' (duration: 978.375333ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:57:44.163557Z","caller":"traceutil/trace.go:171","msg":"trace[47235686] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"977.32489ms","start":"2026-06-05T15:57:43.186216Z","end":"2026-06-05T15:57:44.163541Z","steps":["trace[47235686] 'process raft request' (duration: 977.053212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:44.16368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:43.186197Z","time spent":"977.402113ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T15:57:44.163653Z","caller":"traceutil/trace.go:171","msg":"trace[1854462542] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1141; }","duration":"786.266786ms","start":"2026-06-05T15:57:43.377201Z","end":"2026-06-05T15:57:44.163468Z","steps":["trace[1854462542] 'read index received' (duration: 774.721616ms)","trace[1854462542] 'applied index is now lower than readState.Index' (duration: 11.54362ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:57:44.163768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.567545ms","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-06-05T15:57:44.163821Z","caller":"traceutil/trace.go:171","msg":"trace[1659540113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"786.668878ms","start":"2026-06-05T15:57:43.377142Z","end":"2026-06-05T15:57:44.163811Z","steps":["trace[1659540113] 'agreement among raft nodes before linearized reading' (duration: 786.506773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:44.163856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:43.377118Z","time spent":"786.7313ms","remote":"127.0.0.1:37660","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-06-05T15:57:44.164159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.519752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-05T15:57:44.164233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:43.184737Z","time spent":"978.666082ms","remote":"127.0.0.1:37660","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-06-05T15:57:44.164229Z","caller":"traceutil/trace.go:171","msg":"trace[1693604255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"760.660487ms","start":"2026-06-05T15:57:43.403557Z","end":"2026-06-05T15:57:44.164217Z","steps":["trace[1693604255] 'agreement among raft nodes before linearized reading' (duration: 760.344447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:44.164252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.15796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T15:57:44.164315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:43.403544Z","time spent":"760.760291ms","remote":"127.0.0.1:37660","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-06-05T15:57:44.164341Z","caller":"traceutil/trace.go:171","msg":"trace[1561374519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"236.296884ms","start":"2026-06-05T15:57:43.928023Z","end":"2026-06-05T15:57:44.16432Z","steps":["trace[1561374519] 'agreement among raft nodes before linearized reading' (duration: 236.15372ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:57:45.606892Z","caller":"traceutil/trace.go:171","msg":"trace[1460376534] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"421.946169ms","start":"2026-06-05T15:57:45.184921Z","end":"2026-06-05T15:57:45.606867Z","steps":["trace[1460376534] 'process raft request' (duration: 421.793164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:57:45.607077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:57:45.184896Z","time spent":"422.103023ms","remote":"127.0.0.1:37660","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-06-05T15:57:48.037299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.578667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:57:48.037373Z","caller":"traceutil/trace.go:171","msg":"trace[1355918157] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1111; }","duration":"163.72217ms","start":"2026-06-05T15:57:47.873636Z","end":"2026-06-05T15:57:48.037358Z","steps":["trace[1355918157] 'count revisions from in-memory index tree' (duration: 163.472353ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:02.535113Z","caller":"traceutil/trace.go:171","msg":"trace[1272057972] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"168.360392ms","start":"2026-06-05T15:58:02.366724Z","end":"2026-06-05T15:58:02.535085Z","steps":["trace[1272057972] 'read index received' (duration: 168.199627ms)","trace[1272057972] 'applied index is now lower than readState.Index' (duration: 159.164µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:58:02.535207Z","caller":"traceutil/trace.go:171","msg":"trace[1511690956] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"183.893902ms","start":"2026-06-05T15:58:02.35128Z","end":"2026-06-05T15:58:02.535174Z","steps":["trace[1511690956] 'process raft request' (duration: 183.602694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:02.536267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.528654ms","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-06-05T15:58:02.536336Z","caller":"traceutil/trace.go:171","msg":"trace[1391966343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"169.650858ms","start":"2026-06-05T15:58:02.366668Z","end":"2026-06-05T15:58:02.536319Z","steps":["trace[1391966343] 'agreement among raft nodes before linearized reading' (duration: 168.590357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:02.53712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.367867ms","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-06-05T15:58:02.537174Z","caller":"traceutil/trace.go:171","msg":"trace[1999227765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"129.443238ms","start":"2026-06-05T15:58:02.407717Z","end":"2026-06-05T15:58:02.53716Z","steps":["trace[1999227765] 'agreement among raft nodes before linearized reading' (duration: 129.311815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:02.537534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.963394ms","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-06-05T15:58:02.537581Z","caller":"traceutil/trace.go:171","msg":"trace[514356181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"136.026125ms","start":"2026-06-05T15:58:02.401543Z","end":"2026-06-05T15:58:02.537569Z","steps":["trace[514356181] 'agreement among raft nodes before linearized reading' (duration: 135.906992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:02.541082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.196815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-05T15:58:02.541153Z","caller":"traceutil/trace.go:171","msg":"trace[1757413273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"151.297388ms","start":"2026-06-05T15:58:02.389834Z","end":"2026-06-05T15:58:02.541131Z","steps":["trace[1757413273] 'agreement among raft nodes before linearized reading' (duration: 147.1442ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:04.208474Z","caller":"traceutil/trace.go:171","msg":"trace[481742519] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"521.263904ms","start":"2026-06-05T15:58:03.687185Z","end":"2026-06-05T15:58:04.208449Z","steps":["trace[481742519] 'process raft request' (duration: 521.12134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:04.208637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:03.687161Z","time spent":"521.401107ms","remote":"127.0.0.1:37660","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-06-05T15:58:20.678264Z","caller":"traceutil/trace.go:171","msg":"trace[1470185217] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"417.24247ms","start":"2026-06-05T15:58:20.261004Z","end":"2026-06-05T15:58:20.678246Z","steps":["trace[1470185217] 'process raft request' (duration: 417.117657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:20.67837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.260972Z","time spent":"417.340773ms","remote":"127.0.0.1:37660","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-06-05T15:58:21.353024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.500689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T15:58:21.353125Z","caller":"traceutil/trace.go:171","msg":"trace[131203344] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1341; }","duration":"751.354768ms","start":"2026-06-05T15:58:20.601754Z","end":"2026-06-05T15:58:21.353109Z","steps":["trace[131203344] 'read index received' (duration: 76.400298ms)","trace[131203344] 'applied index is now lower than readState.Index' (duration: 674.95333ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:58:21.353207Z","caller":"traceutil/trace.go:171","msg":"trace[561502476] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"840.193393ms","start":"2026-06-05T15:58:20.513003Z","end":"2026-06-05T15:58:21.353196Z","steps":["trace[561502476] 'process raft request' (duration: 704.446418ms)","trace[561502476] 'compare' (duration: 135.385696ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:58:21.353265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.512985Z","time spent":"840.252805ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T15:58:21.353384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.176167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:58:21.353466Z","caller":"traceutil/trace.go:171","msg":"trace[1219367809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"425.2921ms","start":"2026-06-05T15:58:20.928154Z","end":"2026-06-05T15:58:21.353446Z","steps":["trace[1219367809] 'agreement among raft nodes before linearized reading' (duration: 425.157627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:21.353444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.596867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-05T15:58:21.353538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.928136Z","time spent":"425.393262ms","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T15:58:21.353574Z","caller":"traceutil/trace.go:171","msg":"trace[2142752466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"741.752851ms","start":"2026-06-05T15:58:20.611799Z","end":"2026-06-05T15:58:21.353552Z","steps":["trace[2142752466] 'agreement among raft nodes before linearized reading' (duration: 741.484114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:21.353624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.611785Z","time spent":"741.827292ms","remote":"127.0.0.1:37660","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-06-05T15:58:21.353692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.094515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-05T15:58:21.353705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.433794ms","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-06-05T15:58:21.353792Z","caller":"traceutil/trace.go:171","msg":"trace[1939717855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"737.551646ms","start":"2026-06-05T15:58:20.616223Z","end":"2026-06-05T15:58:21.353775Z","steps":["trace[1939717855] 'agreement among raft nodes before linearized reading' (duration: 737.30492ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:21.353782Z","caller":"traceutil/trace.go:171","msg":"trace[209555292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1289; }","duration":"741.193757ms","start":"2026-06-05T15:58:20.612569Z","end":"2026-06-05T15:58:21.353763Z","steps":["trace[209555292] 'agreement among raft nodes before linearized reading' (duration: 740.90463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:21.35381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.783289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T15:58:21.353844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.616213Z","time spent":"737.621849ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T15:58:21.353848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.612499Z","time spent":"741.339281ms","remote":"127.0.0.1:37660","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-06-05T15:58:21.353863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.104946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T15:58:21.353867Z","caller":"traceutil/trace.go:171","msg":"trace[429547678] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1289; }","duration":"106.874631ms","start":"2026-06-05T15:58:21.246977Z","end":"2026-06-05T15:58:21.353852Z","steps":["trace[429547678] 'agreement among raft nodes before linearized reading' (duration: 106.737548ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:21.353906Z","caller":"traceutil/trace.go:171","msg":"trace[1247421483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"752.169368ms","start":"2026-06-05T15:58:20.601725Z","end":"2026-06-05T15:58:21.353894Z","steps":["trace[1247421483] 'agreement among raft nodes before linearized reading' (duration: 752.030695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:21.353939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.601709Z","time spent":"752.224079ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T15:58:21.354301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.782591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-05T15:58:21.354382Z","caller":"traceutil/trace.go:171","msg":"trace[16942131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"741.894144ms","start":"2026-06-05T15:58:20.612473Z","end":"2026-06-05T15:58:21.354367Z","steps":["trace[16942131] 'agreement among raft nodes before linearized reading' (duration: 741.121135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:21.354526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:20.612461Z","time spent":"742.053878ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T15:58:21.549738Z","caller":"traceutil/trace.go:171","msg":"trace[1156149312] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"191.671907ms","start":"2026-06-05T15:58:21.358047Z","end":"2026-06-05T15:58:21.549718Z","steps":["trace[1156149312] 'process raft request' (duration: 190.873447ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:21.596404Z","caller":"traceutil/trace.go:171","msg":"trace[2071533273] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"238.027143ms","start":"2026-06-05T15:58:21.358352Z","end":"2026-06-05T15:58:21.596379Z","steps":["trace[2071533273] 'process raft request' (duration: 237.957631ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:21.596424Z","caller":"traceutil/trace.go:171","msg":"trace[1017571266] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"238.165486ms","start":"2026-06-05T15:58:21.35823Z","end":"2026-06-05T15:58:21.596395Z","steps":["trace[1017571266] 'process raft request' (duration: 237.954291ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:21.596711Z","caller":"traceutil/trace.go:171","msg":"trace[1143074139] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"237.224582ms","start":"2026-06-05T15:58:21.359456Z","end":"2026-06-05T15:58:21.596681Z","steps":["trace[1143074139] 'process raft request' (duration: 237.103839ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:21.596755Z","caller":"traceutil/trace.go:171","msg":"trace[232513249] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"238.213367ms","start":"2026-06-05T15:58:21.358531Z","end":"2026-06-05T15:58:21.596744Z","steps":["trace[232513249] 'process raft request' (duration: 237.816067ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:23.266654Z","caller":"traceutil/trace.go:171","msg":"trace[1869037479] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"339.284845ms","start":"2026-06-05T15:58:22.927353Z","end":"2026-06-05T15:58:23.266638Z","steps":["trace[1869037479] 'read index received' (duration: 339.110641ms)","trace[1869037479] 'applied index is now lower than readState.Index' (duration: 173.734µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:58:23.266714Z","caller":"traceutil/trace.go:171","msg":"trace[683754394] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"481.186391ms","start":"2026-06-05T15:58:22.785478Z","end":"2026-06-05T15:58:23.266664Z","steps":["trace[683754394] 'process raft request' (duration: 481.055598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:23.266983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.552572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:58:23.267046Z","caller":"traceutil/trace.go:171","msg":"trace[918017558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"339.684105ms","start":"2026-06-05T15:58:22.927348Z","end":"2026-06-05T15:58:23.267032Z","steps":["trace[918017558] 'agreement among raft nodes before linearized reading' (duration: 339.436799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:23.267098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:22.92729Z","time spent":"339.799397ms","remote":"127.0.0.1:37388","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-06-05T15:58:23.267207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:22.785453Z","time spent":"481.593571ms","remote":"127.0.0.1:37470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T15:58:23.721641Z","caller":"traceutil/trace.go:171","msg":"trace[1887029497] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"113.576457ms","start":"2026-06-05T15:58:23.608032Z","end":"2026-06-05T15:58:23.721609Z","steps":["trace[1887029497] 'process raft request' (duration: 113.156886ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:23.721809Z","caller":"traceutil/trace.go:171","msg":"trace[1324941491] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"113.698639ms","start":"2026-06-05T15:58:23.608101Z","end":"2026-06-05T15:58:23.7218Z","steps":["trace[1324941491] 'process raft request' (duration: 113.405952ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:23.721838Z","caller":"traceutil/trace.go:171","msg":"trace[443270689] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"113.530606ms","start":"2026-06-05T15:58:23.608239Z","end":"2026-06-05T15:58:23.721769Z","steps":["trace[443270689] 'process raft request' (duration: 113.422813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:24.154839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.35242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T15:58:24.15493Z","caller":"traceutil/trace.go:171","msg":"trace[703739957] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1302; }","duration":"181.475853ms","start":"2026-06-05T15:58:23.973411Z","end":"2026-06-05T15:58:24.154887Z","steps":["trace[703739957] 'count revisions from in-memory index tree' (duration: 181.238007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:24.155048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.788301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:58:24.155171Z","caller":"traceutil/trace.go:171","msg":"trace[1203505954] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1302; }","duration":"355.931635ms","start":"2026-06-05T15:58:23.799189Z","end":"2026-06-05T15:58:24.155121Z","steps":["trace[1203505954] 'range keys from in-memory index tree' (duration: 355.674049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:24.155173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.706051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T15:58:24.155225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:23.799171Z","time spent":"356.040138ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-05T15:58:24.155422Z","caller":"traceutil/trace.go:171","msg":"trace[1988950651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"226.827084ms","start":"2026-06-05T15:58:23.928402Z","end":"2026-06-05T15:58:24.155229Z","steps":["trace[1988950651] 'range keys from in-memory index tree' (duration: 226.63311ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:24.378137Z","caller":"traceutil/trace.go:171","msg":"trace[1815682035] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"219.459134ms","start":"2026-06-05T15:58:24.158653Z","end":"2026-06-05T15:58:24.378112Z","steps":["trace[1815682035] 'process raft request' (duration: 219.3308ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:24.655178Z","caller":"traceutil/trace.go:171","msg":"trace[1377968026] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"220.278854ms","start":"2026-06-05T15:58:24.434876Z","end":"2026-06-05T15:58:24.655155Z","steps":["trace[1377968026] 'process raft request' (duration: 220.155641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.431061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048150763s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T15:58:33.431267Z","caller":"traceutil/trace.go:171","msg":"trace[1914884503] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1424; }","duration":"1.502543011s","start":"2026-06-05T15:58:31.928705Z","end":"2026-06-05T15:58:33.431248Z","steps":["trace[1914884503] 'read index received' (duration: 454.176843ms)","trace[1914884503] 'applied index is now lower than readState.Index' (duration: 1.048365138s)"],"step_count":2} {"level":"info","ts":"2026-06-05T15:58:33.431342Z","caller":"traceutil/trace.go:171","msg":"trace[269598079] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"1.674038999s","start":"2026-06-05T15:58:31.757291Z","end":"2026-06-05T15:58:33.43133Z","steps":["trace[269598079] 'process raft request' (duration: 625.528848ms)","trace[269598079] 'compare' (duration: 1.048021479s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:58:33.431414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:31.75728Z","time spent":"1.674091251s","remote":"127.0.0.1:37660","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-06-05T15:58:33.431568Z","caller":"traceutil/trace.go:171","msg":"trace[2005656553] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.641190798s","start":"2026-06-05T15:58:31.790368Z","end":"2026-06-05T15:58:33.431559Z","steps":["trace[2005656553] 'process raft request' (duration: 1.640817128s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.43162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:31.790318Z","time spent":"1.64127787s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T15:58:33.431703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503010013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:58:33.431733Z","caller":"traceutil/trace.go:171","msg":"trace[697430392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"1.503106876s","start":"2026-06-05T15:58:31.928617Z","end":"2026-06-05T15:58:33.431724Z","steps":["trace[697430392] 'agreement among raft nodes before linearized reading' (duration: 1.503064235s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.43327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:31.92854Z","time spent":"1.504714366s","remote":"127.0.0.1:37388","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-06-05T15:58:33.812556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.723742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T15:58:33.812595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.706531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-05T15:58:33.812615Z","caller":"traceutil/trace.go:171","msg":"trace[1100441497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"438.796283ms","start":"2026-06-05T15:58:33.373804Z","end":"2026-06-05T15:58:33.8126Z","steps":["trace[1100441497] 'agreement among raft nodes before linearized reading' (duration: 438.697791ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T15:58:33.812646Z","caller":"traceutil/trace.go:171","msg":"trace[1297784323] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1371; }","duration":"423.819004ms","start":"2026-06-05T15:58:33.388813Z","end":"2026-06-05T15:58:33.812632Z","steps":["trace[1297784323] 'agreement among raft nodes before linearized reading' (duration: 423.622209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.812642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:33.373743Z","time spent":"438.893586ms","remote":"127.0.0.1:37388","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-06-05T15:58:33.81268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:33.38879Z","time spent":"423.882565ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-05T15:58:33.81291Z","caller":"traceutil/trace.go:171","msg":"trace[1093290786] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"999.187072ms","start":"2026-06-05T15:58:32.813653Z","end":"2026-06-05T15:58:33.81284Z","steps":["trace[1093290786] 'process raft request' (duration: 998.251119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.813314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:32.813625Z","time spent":"999.522171ms","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T15:58:33.813525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132054976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-05T15:58:33.813557Z","caller":"traceutil/trace.go:171","msg":"trace[863452163] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1371; }","duration":"1.132226171s","start":"2026-06-05T15:58:32.681321Z","end":"2026-06-05T15:58:33.813547Z","steps":["trace[863452163] 'agreement among raft nodes before linearized reading' (duration: 1.131993375s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.813601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:32.6813Z","time spent":"1.132293662s","remote":"127.0.0.1:37660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-05T15:58:33.814313Z","caller":"traceutil/trace.go:171","msg":"trace[859468079] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"381.029817ms","start":"2026-06-05T15:58:33.4313Z","end":"2026-06-05T15:58:33.81233Z","steps":["trace[859468079] 'read index received' (duration: 380.549525ms)","trace[859468079] 'applied index is now lower than readState.Index' (duration: 478.772µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T15:58:33.814503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.191185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T15:58:33.814547Z","caller":"traceutil/trace.go:171","msg":"trace[173315352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"380.256987ms","start":"2026-06-05T15:58:33.434274Z","end":"2026-06-05T15:58:33.814531Z","steps":["trace[173315352] 'agreement among raft nodes before linearized reading' (duration: 380.172475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.814577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T15:58:33.434261Z","time spent":"380.309838ms","remote":"127.0.0.1:37398","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-06-05T15:58:33.815047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.325803ms","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-06-05T15:58:33.815094Z","caller":"traceutil/trace.go:171","msg":"trace[587211430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"173.438876ms","start":"2026-06-05T15:58:33.641642Z","end":"2026-06-05T15:58:33.815081Z","steps":["trace[587211430] 'agreement among raft nodes before linearized reading' (duration: 173.305392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T15:58:33.816015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.976625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T15:58:33.816066Z","caller":"traceutil/trace.go:171","msg":"trace[456732011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"154.057168ms","start":"2026-06-05T15:58:33.661997Z","end":"2026-06-05T15:58:33.816054Z","steps":["trace[456732011] 'agreement among raft nodes before linearized reading' (duration: 152.712784ms)"],"step_count":1}