{"level":"warn","ts":"2026-06-23T22:14:23.072079Z","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-23T22:14:23.072179Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-23T22:14:23.072252Z","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-23T22:14:23.072262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-23T22:14:23.072278Z","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-23T22:14:23.0731Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-06-23T22:14:23.073177Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-23T22:14:23.076403Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.921837ms"} {"level":"info","ts":"2026-06-23T22:14:23.083016Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-06-23T22:14:23.083109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T22:14:23.083134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-06-23T22:14:23.083261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T22:14:23.083277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-06-23T22:14:23.083318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-06-23T22:14:23.092975Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T22:14:23.094563Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T22:14:23.09554Z","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-23T22:14:23.097627Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T22:14:23.097743Z","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-23T22:14:23.097862Z","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-23T22:14:23.097873Z","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-23T22:14:23.098666Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T22:14:23.099171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-06-23T22:14:23.100888Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-06-23T22:14:23.103114Z","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-23T22:14:23.103137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-23T22:14:23.103182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-06-23T22:14:23.103359Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T22:14:23.103406Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T22:14:23.784094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T22:14:23.784151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T22:14:23.784174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-06-23T22:14:23.784193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-06-23T22:14:23.784204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-23T22:14:23.784221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-06-23T22:14:23.78424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-06-23T22:14:23.78543Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T22:14:23.785457Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T22:14:23.785636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T22:14:23.785673Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T22:14:23.78565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T22:14:23.785716Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T22:14:23.786729Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T22:14:23.786823Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T22:14:23.786848Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T22:14:23.788218Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T22:14:23.789436Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-06-23T22:14:49.357717Z","caller":"traceutil/trace.go:171","msg":"trace[497731906] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"152.511986ms","start":"2026-06-23T22:14:49.205183Z","end":"2026-06-23T22:14:49.357695Z","steps":["trace[497731906] 'read index received' (duration: 152.369222ms)","trace[497731906] 'applied index is now lower than readState.Index' (duration: 139.573µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:14:49.357732Z","caller":"traceutil/trace.go:171","msg":"trace[1903508384] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"418.528705ms","start":"2026-06-23T22:14:48.939175Z","end":"2026-06-23T22:14:49.357704Z","steps":["trace[1903508384] 'process raft request' (duration: 418.402502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:14:49.358003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.774651ms","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-23T22:14:49.358194Z","caller":"traceutil/trace.go:171","msg":"trace[2069937002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"153.031077ms","start":"2026-06-23T22:14:49.205132Z","end":"2026-06-23T22:14:49.358163Z","steps":["trace[2069937002] 'agreement among raft nodes before linearized reading' (duration: 152.660619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:14:49.358256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:14:48.939158Z","time spent":"418.647357ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:14:59.283341Z","caller":"traceutil/trace.go:171","msg":"trace[1151565419] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"466.853389ms","start":"2026-06-23T22:14:58.816454Z","end":"2026-06-23T22:14:59.283308Z","steps":["trace[1151565419] 'read index received' (duration: 420.860708ms)","trace[1151565419] 'applied index is now lower than readState.Index' (duration: 45.991511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:14:59.283487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.009392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:14:59.283528Z","caller":"traceutil/trace.go:171","msg":"trace[1824562478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"467.088284ms","start":"2026-06-23T22:14:58.816426Z","end":"2026-06-23T22:14:59.283515Z","steps":["trace[1824562478] 'agreement among raft nodes before linearized reading' (duration: 467.002542ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:14:59.283512Z","caller":"traceutil/trace.go:171","msg":"trace[117414322] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"475.748002ms","start":"2026-06-23T22:14:58.807658Z","end":"2026-06-23T22:14:59.283406Z","steps":["trace[117414322] 'process raft request' (duration: 429.590167ms)","trace[117414322] 'compare' (duration: 45.91847ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:14:59.283598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:14:58.816415Z","time spent":"467.150396ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-23T22:14:59.283705Z","caller":"traceutil/trace.go:171","msg":"trace[1104114645] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"444.512167ms","start":"2026-06-23T22:14:58.839169Z","end":"2026-06-23T22:14:59.283682Z","steps":["trace[1104114645] 'process raft request' (duration: 444.286642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:14:59.28386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:14:58.807639Z","time spent":"475.961986ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:14:59.283936Z","caller":"traceutil/trace.go:171","msg":"trace[709955656] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"439.82325ms","start":"2026-06-23T22:14:58.844082Z","end":"2026-06-23T22:14:59.283905Z","steps":["trace[709955656] 'process raft request' (duration: 439.505383ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:14:59.284043Z","caller":"traceutil/trace.go:171","msg":"trace[664043785] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"419.153338ms","start":"2026-06-23T22:14:58.864863Z","end":"2026-06-23T22:14:59.284016Z","steps":["trace[664043785] 'process raft request' (duration: 418.993584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:14:59.284199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:14:58.864842Z","time spent":"419.298012ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:14:59.284224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:14:58.839149Z","time spent":"444.673652ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:14:59.284081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:14:58.844065Z","time spent":"439.976165ms","remote":"127.0.0.1:36354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-23T22:15:05.093071Z","caller":"traceutil/trace.go:171","msg":"trace[1551794043] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"166.117416ms","start":"2026-06-23T22:15:04.926928Z","end":"2026-06-23T22:15:05.093046Z","steps":["trace[1551794043] 'process raft request' (duration: 165.971773ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:05.764632Z","caller":"traceutil/trace.go:171","msg":"trace[128893107] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"262.506149ms","start":"2026-06-23T22:15:05.502036Z","end":"2026-06-23T22:15:05.764543Z","steps":["trace[128893107] 'read index received' (duration: 256.512502ms)","trace[128893107] 'applied index is now lower than readState.Index' (duration: 5.991797ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:15:05.766317Z","caller":"traceutil/trace.go:171","msg":"trace[941275638] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"287.799297ms","start":"2026-06-23T22:15:05.47847Z","end":"2026-06-23T22:15:05.766269Z","steps":["trace[941275638] 'process raft request' (duration: 281.099664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:05.766565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.596798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-23T22:15:05.76664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.587397ms","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-23T22:15:05.766712Z","caller":"traceutil/trace.go:171","msg":"trace[2127998544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"264.71902ms","start":"2026-06-23T22:15:05.501974Z","end":"2026-06-23T22:15:05.766693Z","steps":["trace[2127998544] 'agreement among raft nodes before linearized reading' (duration: 264.28087ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:05.766712Z","caller":"traceutil/trace.go:171","msg":"trace[566079335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"247.781412ms","start":"2026-06-23T22:15:05.518881Z","end":"2026-06-23T22:15:05.766662Z","steps":["trace[566079335] 'agreement among raft nodes before linearized reading' (duration: 247.522326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:05.767071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.225444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f6mr4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-23T22:15:05.767256Z","caller":"traceutil/trace.go:171","msg":"trace[592127148] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f6mr4; range_end:; response_count:1; response_revision:631; }","duration":"119.48139ms","start":"2026-06-23T22:15:05.647752Z","end":"2026-06-23T22:15:05.767233Z","steps":["trace[592127148] 'agreement among raft nodes before linearized reading' (duration: 118.696322ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:05.968051Z","caller":"traceutil/trace.go:171","msg":"trace[2026363201] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"195.653722ms","start":"2026-06-23T22:15:05.772349Z","end":"2026-06-23T22:15:05.968003Z","steps":["trace[2026363201] 'process raft request' (duration: 156.520588ms)","trace[2026363201] 'compare' (duration: 38.808546ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:15:05.968213Z","caller":"traceutil/trace.go:171","msg":"trace[1737530323] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"164.457449ms","start":"2026-06-23T22:15:05.803722Z","end":"2026-06-23T22:15:05.968179Z","steps":["trace[1737530323] 'read index received' (duration: 125.162971ms)","trace[1737530323] 'applied index is now lower than readState.Index' (duration: 39.292648ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:15:05.968335Z","caller":"traceutil/trace.go:171","msg":"trace[1160686923] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"184.518858ms","start":"2026-06-23T22:15:05.78378Z","end":"2026-06-23T22:15:05.968298Z","steps":["trace[1160686923] 'process raft request' (duration: 184.137449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:05.968516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.780686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:15:05.968553Z","caller":"traceutil/trace.go:171","msg":"trace[781894787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"164.874138ms","start":"2026-06-23T22:15:05.803667Z","end":"2026-06-23T22:15:05.968541Z","steps":["trace[781894787] 'agreement among raft nodes before linearized reading' (duration: 164.650443ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:05.973223Z","caller":"traceutil/trace.go:171","msg":"trace[1603711827] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"145.505116ms","start":"2026-06-23T22:15:05.827699Z","end":"2026-06-23T22:15:05.973205Z","steps":["trace[1603711827] 'process raft request' (duration: 145.355222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:07.007198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.65689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:15:07.007422Z","caller":"traceutil/trace.go:171","msg":"trace[1487092307] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"528.564389ms","start":"2026-06-23T22:15:06.478841Z","end":"2026-06-23T22:15:07.007406Z","steps":["trace[1487092307] 'process raft request' (duration: 528.482017ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:07.007394Z","caller":"traceutil/trace.go:171","msg":"trace[2127493235] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.030449979s","start":"2026-06-23T22:15:05.976906Z","end":"2026-06-23T22:15:07.007356Z","steps":["trace[2127493235] 'process raft request' (duration: 130.056012ms)","trace[2127493235] 'compare' (duration: 899.538307ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:15:07.007462Z","caller":"traceutil/trace.go:171","msg":"trace[1392498331] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"1.01998555s","start":"2026-06-23T22:15:05.987458Z","end":"2026-06-23T22:15:07.007443Z","steps":["trace[1392498331] 'read index received' (duration: 119.539692ms)","trace[1392498331] 'applied index is now lower than readState.Index' (duration: 900.443918ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:15:07.007521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:06.478793Z","time spent":"528.669051ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:15:07.0076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020141023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-06-23T22:15:07.007584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:05.976886Z","time spent":"1.030598342s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:15:07.007631Z","caller":"traceutil/trace.go:171","msg":"trace[778468630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:638; }","duration":"1.020197355s","start":"2026-06-23T22:15:05.987423Z","end":"2026-06-23T22:15:07.00762Z","steps":["trace[778468630] 'agreement among raft nodes before linearized reading' (duration: 1.020125753s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:07.007649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.34508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-23T22:15:07.007664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:05.987407Z","time spent":"1.020249346s","remote":"127.0.0.1:36496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-06-23T22:15:07.007684Z","caller":"traceutil/trace.go:171","msg":"trace[98000520] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:638; }","duration":"418.376561ms","start":"2026-06-23T22:15:06.589296Z","end":"2026-06-23T22:15:07.007672Z","steps":["trace[98000520] 'agreement among raft nodes before linearized reading' (duration: 418.32448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:07.007727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:06.589216Z","time spent":"418.499223ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-06-23T22:15:07.007796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.053322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:15:07.00786Z","caller":"traceutil/trace.go:171","msg":"trace[2132469031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"193.170575ms","start":"2026-06-23T22:15:06.814671Z","end":"2026-06-23T22:15:07.007842Z","steps":["trace[2132469031] 'agreement among raft nodes before linearized reading' (duration: 193.066012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:09.029759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.119193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T22:15:09.029799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.670396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:15:09.029856Z","caller":"traceutil/trace.go:171","msg":"trace[646683043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"214.296787ms","start":"2026-06-23T22:15:08.815535Z","end":"2026-06-23T22:15:09.029832Z","steps":["trace[646683043] 'range keys from in-memory index tree' (duration: 214.044442ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:09.029876Z","caller":"traceutil/trace.go:171","msg":"trace[695577312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:678; }","duration":"186.770649ms","start":"2026-06-23T22:15:08.843086Z","end":"2026-06-23T22:15:09.029857Z","steps":["trace[695577312] 'range keys from in-memory index tree' (duration: 186.598825ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:19.867221Z","caller":"traceutil/trace.go:171","msg":"trace[1734848429] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"114.117878ms","start":"2026-06-23T22:15:19.75299Z","end":"2026-06-23T22:15:19.867108Z","steps":["trace[1734848429] 'process raft request' (duration: 113.821381ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:22.803518Z","caller":"traceutil/trace.go:171","msg":"trace[271839512] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"281.089193ms","start":"2026-06-23T22:15:22.522396Z","end":"2026-06-23T22:15:22.803485Z","steps":["trace[271839512] 'process raft request' (duration: 280.94363ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:31.997654Z","caller":"traceutil/trace.go:171","msg":"trace[1784748667] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:920; }","duration":"120.825541ms","start":"2026-06-23T22:15:31.876801Z","end":"2026-06-23T22:15:31.997626Z","steps":["trace[1784748667] 'read index received' (duration: 120.561065ms)","trace[1784748667] 'applied index is now lower than readState.Index' (duration: 263.376µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:15:31.997733Z","caller":"traceutil/trace.go:171","msg":"trace[438408472] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"133.668885ms","start":"2026-06-23T22:15:31.864021Z","end":"2026-06-23T22:15:31.99769Z","steps":["trace[438408472] 'process raft request' (duration: 133.545772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:31.997888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.052566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-9bxqd\" ","response":"range_response_count:1 size:5086"} {"level":"info","ts":"2026-06-23T22:15:31.997796Z","caller":"traceutil/trace.go:171","msg":"trace[126574517] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"183.425091ms","start":"2026-06-23T22:15:31.814321Z","end":"2026-06-23T22:15:31.997746Z","steps":["trace[126574517] 'process raft request' (duration: 183.061133ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:31.997931Z","caller":"traceutil/trace.go:171","msg":"trace[2079760738] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-9bxqd; range_end:; response_count:1; response_revision:892; }","duration":"121.162409ms","start":"2026-06-23T22:15:31.876758Z","end":"2026-06-23T22:15:31.997921Z","steps":["trace[2079760738] 'agreement among raft nodes before linearized reading' (duration: 120.978475ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:32.046653Z","caller":"traceutil/trace.go:171","msg":"trace[772490954] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"168.062511ms","start":"2026-06-23T22:15:31.878581Z","end":"2026-06-23T22:15:32.046644Z","steps":["trace[772490954] 'process raft request' (duration: 168.00495ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:32.046676Z","caller":"traceutil/trace.go:171","msg":"trace[739459416] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"169.054594ms","start":"2026-06-23T22:15:31.877589Z","end":"2026-06-23T22:15:32.046643Z","steps":["trace[739459416] 'process raft request' (duration: 168.87695ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:15:44.419155Z","caller":"traceutil/trace.go:171","msg":"trace[1029970877] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"117.352127ms","start":"2026-06-23T22:15:44.301767Z","end":"2026-06-23T22:15:44.419119Z","steps":["trace[1029970877] 'process raft request' (duration: 117.163912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.060995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873624140051342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T22:15:59.562197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873624140051342,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T22:15:59.577246Z","caller":"traceutil/trace.go:171","msg":"trace[1756237728] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"1.01713038s","start":"2026-06-23T22:15:58.560089Z","end":"2026-06-23T22:15:59.57722Z","steps":["trace[1756237728] 'read index received' (duration: 1.016955406s)","trace[1756237728] 'applied index is now lower than readState.Index' (duration: 174.284µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:15:59.577291Z","caller":"traceutil/trace.go:171","msg":"trace[657159780] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.274235995s","start":"2026-06-23T22:15:58.303019Z","end":"2026-06-23T22:15:59.577255Z","steps":["trace[657159780] 'process raft request' (duration: 1.274036831s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.577419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017334455s","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-23T22:15:59.577449Z","caller":"traceutil/trace.go:171","msg":"trace[699337524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.017418397s","start":"2026-06-23T22:15:58.56002Z","end":"2026-06-23T22:15:59.577438Z","steps":["trace[699337524] 'agreement among raft nodes before linearized reading' (duration: 1.017289004s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.577443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.018681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-23T22:15:59.577475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:58.559995Z","time spent":"1.017473798s","remote":"127.0.0.1:36564","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-23T22:15:59.577471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:58.302997Z","time spent":"1.274369848s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:15:59.5775Z","caller":"traceutil/trace.go:171","msg":"trace[375659759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"965.094433ms","start":"2026-06-23T22:15:58.61239Z","end":"2026-06-23T22:15:59.577485Z","steps":["trace[375659759] 'agreement among raft nodes before linearized reading' (duration: 964.994251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.577534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:58.612381Z","time spent":"965.145654ms","remote":"127.0.0.1:36564","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-23T22:15:59.577632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.583177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:15:59.577653Z","caller":"traceutil/trace.go:171","msg":"trace[1194228217] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1020; }","duration":"450.641179ms","start":"2026-06-23T22:15:59.127006Z","end":"2026-06-23T22:15:59.577648Z","steps":["trace[1194228217] 'agreement among raft nodes before linearized reading' (duration: 450.603178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.577656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.82057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-23T22:15:59.57767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.126985Z","time spent":"450.680359ms","remote":"127.0.0.1:36546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-06-23T22:15:59.577683Z","caller":"traceutil/trace.go:171","msg":"trace[1317124756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"246.874802ms","start":"2026-06-23T22:15:59.330799Z","end":"2026-06-23T22:15:59.577673Z","steps":["trace[1317124756] 'agreement among raft nodes before linearized reading' (duration: 246.80292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.577768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.11455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:15:59.577783Z","caller":"traceutil/trace.go:171","msg":"trace[1202876331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"312.172481ms","start":"2026-06-23T22:15:59.265606Z","end":"2026-06-23T22:15:59.577778Z","steps":["trace[1202876331] 'agreement among raft nodes before linearized reading' (duration: 312.146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.577799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.265587Z","time spent":"312.208131ms","remote":"127.0.0.1:36302","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-23T22:15:59.577962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.999085ms","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-23T22:15:59.577992Z","caller":"traceutil/trace.go:171","msg":"trace[831646342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"532.079037ms","start":"2026-06-23T22:15:59.045903Z","end":"2026-06-23T22:15:59.577982Z","steps":["trace[831646342] 'agreement among raft nodes before linearized reading' (duration: 531.937673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:15:59.578014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.045876Z","time spent":"532.133938ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T22:16:00.040377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.978051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:16:00.040553Z","caller":"traceutil/trace.go:171","msg":"trace[1147337686] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"459.131993ms","start":"2026-06-23T22:15:59.581398Z","end":"2026-06-23T22:16:00.04053Z","steps":["trace[1147337686] 'process raft request' (duration: 41.686471ms)","trace[1147337686] 'compare' (duration: 416.868739ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:00.040616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.581377Z","time spent":"459.210244ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:00.041107Z","caller":"traceutil/trace.go:171","msg":"trace[737768483] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"459.597163ms","start":"2026-06-23T22:15:59.581497Z","end":"2026-06-23T22:16:00.041094Z","steps":["trace[737768483] 'process raft request' (duration: 458.992419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:00.041184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.581469Z","time spent":"459.668295ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:00.041174Z","caller":"traceutil/trace.go:171","msg":"trace[1164891466] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1056; }","duration":"226.66618ms","start":"2026-06-23T22:15:59.814474Z","end":"2026-06-23T22:16:00.04114Z","steps":["trace[1164891466] 'read index received' (duration: 224.735786ms)","trace[1164891466] 'applied index is now lower than readState.Index' (duration: 1.928984ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:00.041405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.894445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:16:00.04145Z","caller":"traceutil/trace.go:171","msg":"trace[242940340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"227.015858ms","start":"2026-06-23T22:15:59.81442Z","end":"2026-06-23T22:16:00.041436Z","steps":["trace[242940340] 'agreement among raft nodes before linearized reading' (duration: 226.867435ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:00.041699Z","caller":"traceutil/trace.go:171","msg":"trace[1890790964] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"459.980642ms","start":"2026-06-23T22:15:59.581652Z","end":"2026-06-23T22:16:00.041633Z","steps":["trace[1890790964] 'process raft request' (duration: 459.326027ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:00.041759Z","caller":"traceutil/trace.go:171","msg":"trace[913112140] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"460.021073ms","start":"2026-06-23T22:15:59.581702Z","end":"2026-06-23T22:16:00.041723Z","steps":["trace[913112140] 'process raft request' (duration: 459.384149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:00.041876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.581642Z","time spent":"460.142206ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:16:00.041931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:15:59.581682Z","time spent":"460.169846ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:16.283555Z","caller":"traceutil/trace.go:171","msg":"trace[372583584] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"115.199798ms","start":"2026-06-23T22:16:16.168232Z","end":"2026-06-23T22:16:16.283432Z","steps":["trace[372583584] 'process raft request' (duration: 83.648788ms)","trace[372583584] 'compare' (duration: 31.218102ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:16.287631Z","caller":"traceutil/trace.go:171","msg":"trace[398856457] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"118.867572ms","start":"2026-06-23T22:16:16.168732Z","end":"2026-06-23T22:16:16.287599Z","steps":["trace[398856457] 'process raft request' (duration: 118.684657ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:16.287926Z","caller":"traceutil/trace.go:171","msg":"trace[1684743130] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"119.151828ms","start":"2026-06-23T22:16:16.16876Z","end":"2026-06-23T22:16:16.287912Z","steps":["trace[1684743130] 'process raft request' (duration: 118.79318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:22.23449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.782301ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-23T22:16:22.234629Z","caller":"traceutil/trace.go:171","msg":"trace[656728502] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"496.001214ms","start":"2026-06-23T22:16:21.738601Z","end":"2026-06-23T22:16:22.234602Z","steps":["trace[656728502] 'read index received' (duration: 87.999098ms)","trace[656728502] 'applied index is now lower than readState.Index' (duration: 408.000116ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:22.23495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.334322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-23T22:16:22.234945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.467668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:16:22.235012Z","caller":"traceutil/trace.go:171","msg":"trace[130056828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"496.449504ms","start":"2026-06-23T22:16:21.738546Z","end":"2026-06-23T22:16:22.234996Z","steps":["trace[130056828] 'agreement among raft nodes before linearized reading' (duration: 496.182508ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:22.235032Z","caller":"traceutil/trace.go:171","msg":"trace[949749121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"419.605251ms","start":"2026-06-23T22:16:21.815401Z","end":"2026-06-23T22:16:22.235007Z","steps":["trace[949749121] 'agreement among raft nodes before linearized reading' (duration: 419.449518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:22.23506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:21.738523Z","time spent":"496.524785ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T22:16:22.235075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:21.815377Z","time spent":"419.689863ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-23T22:16:24.613105Z","caller":"traceutil/trace.go:171","msg":"trace[1779815592] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"312.860115ms","start":"2026-06-23T22:16:24.300216Z","end":"2026-06-23T22:16:24.613076Z","steps":["trace[1779815592] 'read index received' (duration: 312.691021ms)","trace[1779815592] 'applied index is now lower than readState.Index' (duration: 168.004µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:24.613228Z","caller":"traceutil/trace.go:171","msg":"trace[2070104070] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"327.835427ms","start":"2026-06-23T22:16:24.285336Z","end":"2026-06-23T22:16:24.613171Z","steps":["trace[2070104070] 'process raft request' (duration: 327.564321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:24.613551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.285309Z","time spent":"327.966171ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:16:24.61359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.364908ms","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-23T22:16:24.613647Z","caller":"traceutil/trace.go:171","msg":"trace[1382983755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"313.453529ms","start":"2026-06-23T22:16:24.300176Z","end":"2026-06-23T22:16:24.61363Z","steps":["trace[1382983755] 'agreement among raft nodes before linearized reading' (duration: 313.08109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:24.6137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.300156Z","time spent":"313.532611ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T22:16:25.433632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.93302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:16:25.43374Z","caller":"traceutil/trace.go:171","msg":"trace[578304707] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"820.541236ms","start":"2026-06-23T22:16:24.613179Z","end":"2026-06-23T22:16:25.43372Z","steps":["trace[578304707] 'read index received' (duration: 175.501813ms)","trace[578304707] 'applied index is now lower than readState.Index' (duration: 645.038283ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:25.433866Z","caller":"traceutil/trace.go:171","msg":"trace[90182487] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"1.064345357s","start":"2026-06-23T22:16:24.369507Z","end":"2026-06-23T22:16:25.433852Z","steps":["trace[90182487] 'process raft request' (duration: 419.097819ms)","trace[90182487] 'compare' (duration: 644.807948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:25.433961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.369471Z","time spent":"1.06445659s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:16:25.434227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031868676s","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-23T22:16:25.434305Z","caller":"traceutil/trace.go:171","msg":"trace[2137329621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"1.032001959s","start":"2026-06-23T22:16:24.402285Z","end":"2026-06-23T22:16:25.434287Z","steps":["trace[2137329621] 'agreement among raft nodes before linearized reading' (duration: 1.031753013s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:25.434338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.402263Z","time spent":"1.032068371s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T22:16:25.434516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031579199s","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-23T22:16:25.434554Z","caller":"traceutil/trace.go:171","msg":"trace[110589800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"1.031673322s","start":"2026-06-23T22:16:24.402871Z","end":"2026-06-23T22:16:25.434544Z","steps":["trace[110589800] 'agreement among raft nodes before linearized reading' (duration: 1.031247822s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:25.434596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.402847Z","time spent":"1.031738312s","remote":"127.0.0.1:36564","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-23T22:16:25.43493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048472265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:16:25.434976Z","caller":"traceutil/trace.go:171","msg":"trace[1317596366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"1.048559126s","start":"2026-06-23T22:16:24.386407Z","end":"2026-06-23T22:16:25.434966Z","steps":["trace[1317596366] 'agreement among raft nodes before linearized reading' (duration: 1.047644765s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:25.435011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.386387Z","time spent":"1.048612768s","remote":"127.0.0.1:36564","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-23T22:16:25.661116Z","caller":"traceutil/trace.go:171","msg":"trace[1235845133] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"227.313225ms","start":"2026-06-23T22:16:25.433772Z","end":"2026-06-23T22:16:25.661085Z","steps":["trace[1235845133] 'read index received' (duration: 204.591937ms)","trace[1235845133] 'applied index is now lower than readState.Index' (duration: 22.720338ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:25.661308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.380783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:16:25.661276Z","caller":"traceutil/trace.go:171","msg":"trace[53794497] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.043535653s","start":"2026-06-23T22:16:24.617623Z","end":"2026-06-23T22:16:25.661159Z","steps":["trace[53794497] 'process raft request' (duration: 1.020725782s)","trace[53794497] 'compare' (duration: 22.620246ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:25.66135Z","caller":"traceutil/trace.go:171","msg":"trace[1862471482] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1101; }","duration":"888.494676ms","start":"2026-06-23T22:16:24.772844Z","end":"2026-06-23T22:16:25.661339Z","steps":["trace[1862471482] 'agreement among raft nodes before linearized reading' (duration: 888.402234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:25.661443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.772816Z","time spent":"888.611899ms","remote":"127.0.0.1:46706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-06-23T22:16:25.661536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:24.617599Z","time spent":"1.043798688s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:25.841753Z","caller":"traceutil/trace.go:171","msg":"trace[1340535144] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1142; }","duration":"180.504577ms","start":"2026-06-23T22:16:25.661218Z","end":"2026-06-23T22:16:25.841723Z","steps":["trace[1340535144] 'read index received' (duration: 159.769834ms)","trace[1340535144] 'applied index is now lower than readState.Index' (duration: 20.733653ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:25.841931Z","caller":"traceutil/trace.go:171","msg":"trace[704959859] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"403.392431ms","start":"2026-06-23T22:16:25.438511Z","end":"2026-06-23T22:16:25.841903Z","steps":["trace[704959859] 'process raft request' (duration: 382.588656ms)","trace[704959859] 'compare' (duration: 20.321604ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:25.84203Z","caller":"traceutil/trace.go:171","msg":"trace[78219816] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"403.163666ms","start":"2026-06-23T22:16:25.438807Z","end":"2026-06-23T22:16:25.84197Z","steps":["trace[78219816] 'process raft request' (duration: 402.772607ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:25.84207Z","caller":"traceutil/trace.go:171","msg":"trace[919013786] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"402.771497ms","start":"2026-06-23T22:16:25.439268Z","end":"2026-06-23T22:16:25.84204Z","steps":["trace[919013786] 'process raft request' (duration: 402.383998ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:25.842178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.855788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T22:16:25.842246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:25.438796Z","time spent":"403.331179ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:16:25.842254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:25.438491Z","time spent":"403.511633ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:25.842291Z","caller":"traceutil/trace.go:171","msg":"trace[2075485669] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1104; }","duration":"310.112593ms","start":"2026-06-23T22:16:25.532151Z","end":"2026-06-23T22:16:25.842263Z","steps":["trace[2075485669] 'agreement among raft nodes before linearized reading' (duration: 309.770836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:25.842306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:25.439241Z","time spent":"402.93125ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:16:25.842352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:25.532098Z","time spent":"310.240076ms","remote":"127.0.0.1:36658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-06-23T22:16:28.811867Z","caller":"traceutil/trace.go:171","msg":"trace[1389421784] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"100.260837ms","start":"2026-06-23T22:16:28.71119Z","end":"2026-06-23T22:16:28.811451Z","steps":["trace[1389421784] 'process raft request' (duration: 100.072253ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:39.199214Z","caller":"traceutil/trace.go:171","msg":"trace[1950347005] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"141.752303ms","start":"2026-06-23T22:16:39.057438Z","end":"2026-06-23T22:16:39.19919Z","steps":["trace[1950347005] 'process raft request' (duration: 141.59983ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:39.345319Z","caller":"traceutil/trace.go:171","msg":"trace[2142564340] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"135.685092ms","start":"2026-06-23T22:16:39.209588Z","end":"2026-06-23T22:16:39.345273Z","steps":["trace[2142564340] 'process raft request' (duration: 114.266532ms)","trace[2142564340] 'compare' (duration: 21.251276ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:39.345503Z","caller":"traceutil/trace.go:171","msg":"trace[984135915] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"135.841265ms","start":"2026-06-23T22:16:39.209638Z","end":"2026-06-23T22:16:39.34548Z","steps":["trace[984135915] 'process raft request' (duration: 135.753983ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:39.528809Z","caller":"traceutil/trace.go:171","msg":"trace[161464792] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"147.306633ms","start":"2026-06-23T22:16:39.381476Z","end":"2026-06-23T22:16:39.528783Z","steps":["trace[161464792] 'process raft request' (duration: 137.953025ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:39.528835Z","caller":"traceutil/trace.go:171","msg":"trace[832398202] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"146.707529ms","start":"2026-06-23T22:16:39.3821Z","end":"2026-06-23T22:16:39.528808Z","steps":["trace[832398202] 'process raft request' (duration: 146.532775ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:43.642275Z","caller":"traceutil/trace.go:171","msg":"trace[535725802] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"701.868287ms","start":"2026-06-23T22:16:42.940381Z","end":"2026-06-23T22:16:43.642249Z","steps":["trace[535725802] 'process raft request' (duration: 701.702283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:43.642436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:42.940345Z","time spent":"702.003589ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:44.164223Z","caller":"traceutil/trace.go:171","msg":"trace[395579180] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"369.735106ms","start":"2026-06-23T22:16:43.794459Z","end":"2026-06-23T22:16:44.164194Z","steps":["trace[395579180] 'read index received' (duration: 367.716469ms)","trace[395579180] 'applied index is now lower than readState.Index' (duration: 2.017517ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:44.164255Z","caller":"traceutil/trace.go:171","msg":"trace[1032906075] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"604.467236ms","start":"2026-06-23T22:16:43.559768Z","end":"2026-06-23T22:16:44.164236Z","steps":["trace[1032906075] 'process raft request' (duration: 602.414009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:44.164367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.416012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T22:16:44.16437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:43.559746Z","time spent":"604.552718ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:16:44.164406Z","caller":"traceutil/trace.go:171","msg":"trace[1839780627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"349.496834ms","start":"2026-06-23T22:16:43.814896Z","end":"2026-06-23T22:16:44.164393Z","steps":["trace[1839780627] 'agreement among raft nodes before linearized reading' (duration: 349.427352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:44.16442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.947431ms","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-23T22:16:44.164489Z","caller":"traceutil/trace.go:171","msg":"trace[1803680474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"370.088584ms","start":"2026-06-23T22:16:43.794383Z","end":"2026-06-23T22:16:44.164471Z","steps":["trace[1803680474] 'agreement among raft nodes before linearized reading' (duration: 369.92291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:44.164526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:43.794365Z","time spent":"370.154256ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T22:16:44.164564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.406714ms","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-23T22:16:44.164592Z","caller":"traceutil/trace.go:171","msg":"trace[646947577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"186.455335ms","start":"2026-06-23T22:16:43.978129Z","end":"2026-06-23T22:16:44.164584Z","steps":["trace[646947577] 'agreement among raft nodes before linearized reading' (duration: 186.336413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:44.164443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:43.81488Z","time spent":"349.553526ms","remote":"127.0.0.1:36302","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-23T22:16:44.164573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.244774ms","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-23T22:16:44.16465Z","caller":"traceutil/trace.go:171","msg":"trace[139189084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"181.355606ms","start":"2026-06-23T22:16:43.983275Z","end":"2026-06-23T22:16:44.164631Z","steps":["trace[139189084] 'agreement among raft nodes before linearized reading' (duration: 181.189383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:44.164712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.707516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T22:16:44.164751Z","caller":"traceutil/trace.go:171","msg":"trace[1449854826] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1211; }","duration":"224.780838ms","start":"2026-06-23T22:16:43.939958Z","end":"2026-06-23T22:16:44.164738Z","steps":["trace[1449854826] 'agreement among raft nodes before linearized reading' (duration: 224.713036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:44.164909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.452229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:16:44.164949Z","caller":"traceutil/trace.go:171","msg":"trace[1656148426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"181.5084ms","start":"2026-06-23T22:16:43.983432Z","end":"2026-06-23T22:16:44.16494Z","steps":["trace[1656148426] 'agreement among raft nodes before linearized reading' (duration: 181.414567ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:49.926743Z","caller":"traceutil/trace.go:171","msg":"trace[1108702679] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"114.176611ms","start":"2026-06-23T22:16:49.812527Z","end":"2026-06-23T22:16:49.926703Z","steps":["trace[1108702679] 'read index received' (duration: 113.780382ms)","trace[1108702679] 'applied index is now lower than readState.Index' (duration: 394.609µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:49.926837Z","caller":"traceutil/trace.go:171","msg":"trace[1087296343] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"256.399836ms","start":"2026-06-23T22:16:49.670402Z","end":"2026-06-23T22:16:49.926802Z","steps":["trace[1087296343] 'process raft request' (duration: 256.059598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:49.926952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.404026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:16:49.927016Z","caller":"traceutil/trace.go:171","msg":"trace[835778987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"114.529329ms","start":"2026-06-23T22:16:49.81247Z","end":"2026-06-23T22:16:49.926999Z","steps":["trace[835778987] 'agreement among raft nodes before linearized reading' (duration: 114.396516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:50.591094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.71596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:16:50.591195Z","caller":"traceutil/trace.go:171","msg":"trace[995310823] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"321.666497ms","start":"2026-06-23T22:16:50.269508Z","end":"2026-06-23T22:16:50.591175Z","steps":["trace[995310823] 'read index received' (duration: 95.740432ms)","trace[995310823] 'applied index is now lower than readState.Index' (duration: 225.924604ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:50.591202Z","caller":"traceutil/trace.go:171","msg":"trace[1228149588] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"343.839353ms","start":"2026-06-23T22:16:50.247347Z","end":"2026-06-23T22:16:50.591186Z","steps":["trace[1228149588] 'process raft request' (duration: 117.954279ms)","trace[1228149588] 'compare' (duration: 225.580017ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:50.5913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:50.24733Z","time spent":"343.943595ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:16:50.591373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.859721ms","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-23T22:16:50.591402Z","caller":"traceutil/trace.go:171","msg":"trace[1808556845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"321.912952ms","start":"2026-06-23T22:16:50.26948Z","end":"2026-06-23T22:16:50.591393Z","steps":["trace[1808556845] 'agreement among raft nodes before linearized reading' (duration: 321.794949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:50.591431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:50.269468Z","time spent":"321.955852ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T22:16:50.591557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.224689ms","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-23T22:16:50.591578Z","caller":"traceutil/trace.go:171","msg":"trace[149249180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"313.275011ms","start":"2026-06-23T22:16:50.278297Z","end":"2026-06-23T22:16:50.591572Z","steps":["trace[149249180] 'agreement among raft nodes before linearized reading' (duration: 313.213779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:50.591597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:50.278284Z","time spent":"313.308281ms","remote":"127.0.0.1:36564","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-23T22:16:50.591611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.942662ms","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-23T22:16:50.591656Z","caller":"traceutil/trace.go:171","msg":"trace[1407832881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"322.024295ms","start":"2026-06-23T22:16:50.269621Z","end":"2026-06-23T22:16:50.591646Z","steps":["trace[1407832881] 'agreement among raft nodes before linearized reading' (duration: 321.882211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:16:50.591678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:50.269607Z","time spent":"322.067015ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-23T22:16:50.745553Z","caller":"traceutil/trace.go:171","msg":"trace[588221988] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"150.279341ms","start":"2026-06-23T22:16:50.59525Z","end":"2026-06-23T22:16:50.74553Z","steps":["trace[588221988] 'process raft request' (duration: 150.186949ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:50.745761Z","caller":"traceutil/trace.go:171","msg":"trace[792673114] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"151.297116ms","start":"2026-06-23T22:16:50.594453Z","end":"2026-06-23T22:16:50.74575Z","steps":["trace[792673114] 'process raft request' (duration: 119.28958ms)","trace[792673114] 'compare' (duration: 31.564895ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:16:50.746054Z","caller":"traceutil/trace.go:171","msg":"trace[224885538] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"150.705712ms","start":"2026-06-23T22:16:50.595325Z","end":"2026-06-23T22:16:50.746031Z","steps":["trace[224885538] 'process raft request' (duration: 150.17175ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:56.738492Z","caller":"traceutil/trace.go:171","msg":"trace[1688679003] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"125.880673ms","start":"2026-06-23T22:16:56.612586Z","end":"2026-06-23T22:16:56.738466Z","steps":["trace[1688679003] 'read index received' (duration: 125.600687ms)","trace[1688679003] 'applied index is now lower than readState.Index' (duration: 278.156µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:16:56.738739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.128249ms","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-23T22:16:56.738781Z","caller":"traceutil/trace.go:171","msg":"trace[828684673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"126.226821ms","start":"2026-06-23T22:16:56.612543Z","end":"2026-06-23T22:16:56.73877Z","steps":["trace[828684673] 'agreement among raft nodes before linearized reading' (duration: 126.024106ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:16:58.396536Z","caller":"traceutil/trace.go:171","msg":"trace[1224986971] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"113.494685ms","start":"2026-06-23T22:16:58.283021Z","end":"2026-06-23T22:16:58.396516Z","steps":["trace[1224986971] 'process raft request' (duration: 98.68021ms)","trace[1224986971] 'compare' (duration: 14.710113ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:00.073955Z","caller":"traceutil/trace.go:171","msg":"trace[1568677079] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"258.544895ms","start":"2026-06-23T22:16:59.815388Z","end":"2026-06-23T22:17:00.073933Z","steps":["trace[1568677079] 'read index received' (duration: 258.35409ms)","trace[1568677079] 'applied index is now lower than readState.Index' (duration: 189.925µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:00.074005Z","caller":"traceutil/trace.go:171","msg":"trace[464549151] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"498.395084ms","start":"2026-06-23T22:16:59.575574Z","end":"2026-06-23T22:17:00.073969Z","steps":["trace[464549151] 'process raft request' (duration: 498.21875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:00.074147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:16:59.575556Z","time spent":"498.529417ms","remote":"127.0.0.1:36362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T22:17:00.074154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.55205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T22:17:00.074188Z","caller":"traceutil/trace.go:171","msg":"trace[1130939572] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1263; }","duration":"111.647482ms","start":"2026-06-23T22:16:59.962531Z","end":"2026-06-23T22:17:00.074178Z","steps":["trace[1130939572] 'agreement among raft nodes before linearized reading' (duration: 111.52927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:00.074168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.75572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:00.074238Z","caller":"traceutil/trace.go:171","msg":"trace[504702645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"258.873042ms","start":"2026-06-23T22:16:59.815347Z","end":"2026-06-23T22:17:00.07422Z","steps":["trace[504702645] 'agreement among raft nodes before linearized reading' (duration: 258.701938ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:02.863114Z","caller":"traceutil/trace.go:171","msg":"trace[981198031] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"137.30861ms","start":"2026-06-23T22:17:02.725777Z","end":"2026-06-23T22:17:02.863085Z","steps":["trace[981198031] 'process raft request' (duration: 137.115435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:07.127393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.061786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:17:07.127476Z","caller":"traceutil/trace.go:171","msg":"trace[902583385] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"312.867311ms","start":"2026-06-23T22:17:06.814594Z","end":"2026-06-23T22:17:07.127462Z","steps":["trace[902583385] 'read index received' (duration: 45.572µs)","trace[902583385] 'applied index is now lower than readState.Index' (duration: 312.820739ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:07.127531Z","caller":"traceutil/trace.go:171","msg":"trace[1951162852] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"504.915976ms","start":"2026-06-23T22:17:06.622586Z","end":"2026-06-23T22:17:07.127501Z","steps":["trace[1951162852] 'process raft request' (duration: 85.674066ms)","trace[1951162852] 'compare' (duration: 418.688107ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:17:07.127609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.012404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:07.127646Z","caller":"traceutil/trace.go:171","msg":"trace[771663928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"313.094806ms","start":"2026-06-23T22:17:06.814541Z","end":"2026-06-23T22:17:07.127636Z","steps":["trace[771663928] 'agreement among raft nodes before linearized reading' (duration: 312.996984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:07.127669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:06.622541Z","time spent":"505.05561ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:07.127823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.280419ms","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-23T22:17:07.127829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.602656ms","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-23T22:17:07.12768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:06.814483Z","time spent":"313.189888ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-23T22:17:07.12791Z","caller":"traceutil/trace.go:171","msg":"trace[465833680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"152.747279ms","start":"2026-06-23T22:17:06.975144Z","end":"2026-06-23T22:17:07.127891Z","steps":["trace[465833680] 'agreement among raft nodes before linearized reading' (duration: 152.447012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:07.127951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.423665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:17:07.128011Z","caller":"traceutil/trace.go:171","msg":"trace[1738541095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"147.533958ms","start":"2026-06-23T22:17:06.980461Z","end":"2026-06-23T22:17:07.127995Z","steps":["trace[1738541095] 'agreement among raft nodes before linearized reading' (duration: 147.259081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:07.128039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.714463ms","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-23T22:17:07.128074Z","caller":"traceutil/trace.go:171","msg":"trace[1620543209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"147.768523ms","start":"2026-06-23T22:17:06.980297Z","end":"2026-06-23T22:17:07.128066Z","steps":["trace[1620543209] 'agreement among raft nodes before linearized reading' (duration: 147.690002ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:07.127862Z","caller":"traceutil/trace.go:171","msg":"trace[1487741174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"146.33345ms","start":"2026-06-23T22:17:06.981518Z","end":"2026-06-23T22:17:07.127851Z","steps":["trace[1487741174] 'agreement among raft nodes before linearized reading' (duration: 146.184347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:07.63368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873624140052028,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T22:17:08.077135Z","caller":"traceutil/trace.go:171","msg":"trace[2077277330] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"945.37631ms","start":"2026-06-23T22:17:07.131735Z","end":"2026-06-23T22:17:08.077112Z","steps":["trace[2077277330] 'process raft request' (duration: 945.229587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:08.078924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:07.13172Z","time spent":"947.08432ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:08.484377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.000283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:17:08.484548Z","caller":"traceutil/trace.go:171","msg":"trace[1864247080] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1363; }","duration":"1.351645067s","start":"2026-06-23T22:17:07.132875Z","end":"2026-06-23T22:17:08.48452Z","steps":["trace[1864247080] 'read index received' (duration: 944.1057ms)","trace[1864247080] 'applied index is now lower than readState.Index' (duration: 407.538687ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:08.484597Z","caller":"traceutil/trace.go:171","msg":"trace[77755543] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.351676308s","start":"2026-06-23T22:17:07.132881Z","end":"2026-06-23T22:17:08.484557Z","steps":["trace[77755543] 'process raft request' (duration: 1.247437339s)","trace[77755543] 'compare' (duration: 103.798619ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:08.48462Z","caller":"traceutil/trace.go:171","msg":"trace[1723008785] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.351715849s","start":"2026-06-23T22:17:07.132882Z","end":"2026-06-23T22:17:08.484598Z","steps":["trace[1723008785] 'process raft request' (duration: 1.351556315s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:08.484745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351867462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-23T22:17:08.484768Z","caller":"traceutil/trace.go:171","msg":"trace[1752543814] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1315; }","duration":"1.351911624s","start":"2026-06-23T22:17:07.13285Z","end":"2026-06-23T22:17:08.484762Z","steps":["trace[1752543814] 'agreement among raft nodes before linearized reading' (duration: 1.351808681s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:08.484758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:07.132867Z","time spent":"1.351810582s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:08.484801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:07.13284Z","time spent":"1.351955465s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-06-23T22:17:08.484742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:07.132863Z","time spent":"1.351818382s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:08.484954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.891832ms","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-23T22:17:08.48499Z","caller":"traceutil/trace.go:171","msg":"trace[100633346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"182.957823ms","start":"2026-06-23T22:17:08.302024Z","end":"2026-06-23T22:17:08.484982Z","steps":["trace[100633346] 'agreement among raft nodes before linearized reading' (duration: 182.862971ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:08.484647Z","caller":"traceutil/trace.go:171","msg":"trace[766388649] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.351643578s","start":"2026-06-23T22:17:07.132996Z","end":"2026-06-23T22:17:08.48464Z","steps":["trace[766388649] 'process raft request' (duration: 1.351469634s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:08.485086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:07.132972Z","time spent":"1.352080458s","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:17:08.69622Z","caller":"traceutil/trace.go:171","msg":"trace[1695656673] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"130.930881ms","start":"2026-06-23T22:17:08.565261Z","end":"2026-06-23T22:17:08.696192Z","steps":["trace[1695656673] 'process raft request' (duration: 130.745427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:08.983733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.845797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T22:17:08.983775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.506478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-23T22:17:08.983815Z","caller":"traceutil/trace.go:171","msg":"trace[1192981036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"168.971559ms","start":"2026-06-23T22:17:08.814824Z","end":"2026-06-23T22:17:08.983795Z","steps":["trace[1192981036] 'range keys from in-memory index tree' (duration: 168.769144ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:08.983835Z","caller":"traceutil/trace.go:171","msg":"trace[335433277] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1318; }","duration":"183.60786ms","start":"2026-06-23T22:17:08.800213Z","end":"2026-06-23T22:17:08.98382Z","steps":["trace[335433277] 'range keys from in-memory index tree' (duration: 183.272963ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:09.157029Z","caller":"traceutil/trace.go:171","msg":"trace[727770914] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"168.342401ms","start":"2026-06-23T22:17:08.988667Z","end":"2026-06-23T22:17:09.15701Z","steps":["trace[727770914] 'process raft request' (duration: 168.217958ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:09.772674Z","caller":"traceutil/trace.go:171","msg":"trace[1012747287] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"308.962419ms","start":"2026-06-23T22:17:09.463671Z","end":"2026-06-23T22:17:09.772634Z","steps":["trace[1012747287] 'process raft request' (duration: 308.763974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:09.772876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:09.463646Z","time spent":"309.135482ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:10.25659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.377493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T22:17:10.256608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.662009ms","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-23T22:17:10.256675Z","caller":"traceutil/trace.go:171","msg":"trace[1443845980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"441.489927ms","start":"2026-06-23T22:17:09.815163Z","end":"2026-06-23T22:17:10.256653Z","steps":["trace[1443845980] 'range keys from in-memory index tree' (duration: 441.317102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.25673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:09.815155Z","time spent":"441.566098ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-23T22:17:10.256688Z","caller":"traceutil/trace.go:171","msg":"trace[726998868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1320; }","duration":"171.793963ms","start":"2026-06-23T22:17:10.084874Z","end":"2026-06-23T22:17:10.256668Z","steps":["trace[726998868] 'range keys from in-memory index tree' (duration: 171.404373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.855363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.300438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:10.855843Z","caller":"traceutil/trace.go:171","msg":"trace[663488549] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1321; }","duration":"309.849921ms","start":"2026-06-23T22:17:10.545963Z","end":"2026-06-23T22:17:10.855813Z","steps":["trace[663488549] 'count revisions from in-memory index tree' (duration: 309.151634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.855964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:10.545939Z","time spent":"310.005624ms","remote":"127.0.0.1:46796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-23T22:17:10.855466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.956711ms","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-23T22:17:10.856156Z","caller":"traceutil/trace.go:171","msg":"trace[641343865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"367.659427ms","start":"2026-06-23T22:17:10.488453Z","end":"2026-06-23T22:17:10.856112Z","steps":["trace[641343865] 'range keys from in-memory index tree' (duration: 366.827788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.855551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.009342ms","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-23T22:17:10.856283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:10.488438Z","time spent":"367.811691ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-23T22:17:10.856284Z","caller":"traceutil/trace.go:171","msg":"trace[2018533836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"367.77588ms","start":"2026-06-23T22:17:10.488489Z","end":"2026-06-23T22:17:10.856265Z","steps":["trace[2018533836] 'range keys from in-memory index tree' (duration: 366.867759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.856452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:10.488466Z","time spent":"367.956604ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T22:17:10.855607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.812448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:17:10.856582Z","caller":"traceutil/trace.go:171","msg":"trace[1207106388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"367.830771ms","start":"2026-06-23T22:17:10.488728Z","end":"2026-06-23T22:17:10.856559Z","steps":["trace[1207106388] 'range keys from in-memory index tree' (duration: 366.719855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.856613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:10.488712Z","time spent":"367.894602ms","remote":"127.0.0.1:36564","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-23T22:17:10.939541Z","caller":"traceutil/trace.go:171","msg":"trace[2111880395] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"366.164534ms","start":"2026-06-23T22:17:10.573358Z","end":"2026-06-23T22:17:10.939523Z","steps":["trace[2111880395] 'read index received' (duration: 366.097962ms)","trace[2111880395] 'applied index is now lower than readState.Index' (duration: 65.252µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:17:10.939857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.43891ms","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-23T22:17:10.939943Z","caller":"traceutil/trace.go:171","msg":"trace[1509827971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"366.618034ms","start":"2026-06-23T22:17:10.573307Z","end":"2026-06-23T22:17:10.939925Z","steps":["trace[1509827971] 'agreement among raft nodes before linearized reading' (duration: 366.347918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.94016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:10.573288Z","time spent":"366.85763ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-23T22:17:10.940538Z","caller":"traceutil/trace.go:171","msg":"trace[144835025] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"375.712966ms","start":"2026-06-23T22:17:10.564643Z","end":"2026-06-23T22:17:10.940356Z","steps":["trace[144835025] 'process raft request' (duration: 374.754904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:10.940681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:10.564618Z","time spent":"375.991063ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:17:11.036951Z","caller":"traceutil/trace.go:171","msg":"trace[2030617217] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"177.588199ms","start":"2026-06-23T22:17:10.859344Z","end":"2026-06-23T22:17:11.036932Z","steps":["trace[2030617217] 'process raft request' (duration: 177.419276ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:11.037311Z","caller":"traceutil/trace.go:171","msg":"trace[22505498] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"176.930394ms","start":"2026-06-23T22:17:10.860362Z","end":"2026-06-23T22:17:11.037293Z","steps":["trace[22505498] 'process raft request' (duration: 176.530625ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:11.037669Z","caller":"traceutil/trace.go:171","msg":"trace[1199775759] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"177.20921ms","start":"2026-06-23T22:17:10.860442Z","end":"2026-06-23T22:17:11.037651Z","steps":["trace[1199775759] 'process raft request' (duration: 176.78841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:11.037894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.206472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:11.037969Z","caller":"traceutil/trace.go:171","msg":"trace[254167504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"223.314354ms","start":"2026-06-23T22:17:10.814642Z","end":"2026-06-23T22:17:11.037957Z","steps":["trace[254167504] 'agreement among raft nodes before linearized reading' (duration: 223.209982ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:11.064571Z","caller":"traceutil/trace.go:171","msg":"trace[1028377540] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"119.901444ms","start":"2026-06-23T22:17:10.944652Z","end":"2026-06-23T22:17:11.064554Z","steps":["trace[1028377540] 'process raft request' (duration: 119.70445ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:11.064609Z","caller":"traceutil/trace.go:171","msg":"trace[987663925] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"119.111246ms","start":"2026-06-23T22:17:10.945475Z","end":"2026-06-23T22:17:11.064587Z","steps":["trace[987663925] 'process raft request' (duration: 118.969203ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:12.966365Z","caller":"traceutil/trace.go:171","msg":"trace[1130418955] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1384; }","duration":"681.309228ms","start":"2026-06-23T22:17:12.285004Z","end":"2026-06-23T22:17:12.966314Z","steps":["trace[1130418955] 'read index received' (duration: 102.048039ms)","trace[1130418955] 'applied index is now lower than readState.Index' (duration: 579.255249ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:12.967052Z","caller":"traceutil/trace.go:171","msg":"trace[1732599151] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"888.065695ms","start":"2026-06-23T22:17:12.078954Z","end":"2026-06-23T22:17:12.967019Z","steps":["trace[1732599151] 'process raft request' (duration: 887.026561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:12.967289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:12.078932Z","time spent":"888.207979ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:12.967727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.70653ms","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-23T22:17:12.967828Z","caller":"traceutil/trace.go:171","msg":"trace[574541781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1333; }","duration":"682.843223ms","start":"2026-06-23T22:17:12.284954Z","end":"2026-06-23T22:17:12.967797Z","steps":["trace[574541781] 'agreement among raft nodes before linearized reading' (duration: 682.559277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:12.967878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:12.284933Z","time spent":"682.932255ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T22:17:13.382846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.03873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:17:13.383014Z","caller":"traceutil/trace.go:171","msg":"trace[1248967937] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"436.605875ms","start":"2026-06-23T22:17:12.946376Z","end":"2026-06-23T22:17:13.382982Z","steps":["trace[1248967937] 'process raft request' (duration: 285.298378ms)","trace[1248967937] 'compare' (duration: 150.649371ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:13.383109Z","caller":"traceutil/trace.go:171","msg":"trace[684213712] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"409.964083ms","start":"2026-06-23T22:17:12.973122Z","end":"2026-06-23T22:17:13.383086Z","steps":["trace[684213712] 'process raft request' (duration: 409.856601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:13.383178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:12.946342Z","time spent":"436.746778ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:17:13.383221Z","caller":"traceutil/trace.go:171","msg":"trace[32677139] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"416.710371ms","start":"2026-06-23T22:17:12.9665Z","end":"2026-06-23T22:17:13.383211Z","steps":["trace[32677139] 'read index received' (duration: 265.19318ms)","trace[32677139] 'applied index is now lower than readState.Index' (duration: 151.516001ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:17:13.383186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:12.973088Z","time spent":"410.063466ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:13.383613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.74971ms","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-23T22:17:13.383655Z","caller":"traceutil/trace.go:171","msg":"trace[660665513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"340.814472ms","start":"2026-06-23T22:17:13.04283Z","end":"2026-06-23T22:17:13.383645Z","steps":["trace[660665513] 'agreement among raft nodes before linearized reading' (duration: 340.668089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:13.38368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:13.042819Z","time spent":"340.855323ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-23T22:17:13.383723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.791815ms","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-23T22:17:13.383792Z","caller":"traceutil/trace.go:171","msg":"trace[427311212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"314.925899ms","start":"2026-06-23T22:17:13.068847Z","end":"2026-06-23T22:17:13.383773Z","steps":["trace[427311212] 'agreement among raft nodes before linearized reading' (duration: 314.693694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:13.383834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:13.068827Z","time spent":"315.00089ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-23T22:17:13.383834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.786821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:17:13.383867Z","caller":"traceutil/trace.go:171","msg":"trace[570928689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"340.873693ms","start":"2026-06-23T22:17:13.042985Z","end":"2026-06-23T22:17:13.383859Z","steps":["trace[570928689] 'agreement among raft nodes before linearized reading' (duration: 340.785411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:13.383895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:13.042954Z","time spent":"340.937175ms","remote":"127.0.0.1:36564","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-23T22:17:13.383913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.732348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:13.383935Z","caller":"traceutil/trace.go:171","msg":"trace[160571445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"569.79042ms","start":"2026-06-23T22:17:12.814138Z","end":"2026-06-23T22:17:13.383928Z","steps":["trace[160571445] 'agreement among raft nodes before linearized reading' (duration: 569.757119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:13.383951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:12.814117Z","time spent":"569.83081ms","remote":"127.0.0.1:36302","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-23T22:17:13.384085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.222331ms","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-23T22:17:13.384117Z","caller":"traceutil/trace.go:171","msg":"trace[966417616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"341.307603ms","start":"2026-06-23T22:17:13.0428Z","end":"2026-06-23T22:17:13.384108Z","steps":["trace[966417616] 'agreement among raft nodes before linearized reading' (duration: 341.216761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:13.384138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:13.042772Z","time spent":"341.361154ms","remote":"127.0.0.1:36564","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-23T22:17:16.859429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.619071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:17:16.859565Z","caller":"traceutil/trace.go:171","msg":"trace[1057152475] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"198.19981ms","start":"2026-06-23T22:17:16.661342Z","end":"2026-06-23T22:17:16.859542Z","steps":["trace[1057152475] 'read index received' (duration: 80.369834ms)","trace[1057152475] 'applied index is now lower than readState.Index' (duration: 117.825096ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:17:16.859904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.542468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:16.859889Z","caller":"traceutil/trace.go:171","msg":"trace[510965600] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"221.200456ms","start":"2026-06-23T22:17:16.638658Z","end":"2026-06-23T22:17:16.859859Z","steps":["trace[510965600] 'process raft request' (duration: 103.048982ms)","trace[510965600] 'compare' (duration: 117.498948ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:16.85996Z","caller":"traceutil/trace.go:171","msg":"trace[1524543298] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1355; }","duration":"198.60842ms","start":"2026-06-23T22:17:16.66134Z","end":"2026-06-23T22:17:16.859948Z","steps":["trace[1524543298] 'agreement among raft nodes before linearized reading' (duration: 198.265342ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:17.74928Z","caller":"traceutil/trace.go:171","msg":"trace[1546373805] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"308.383928ms","start":"2026-06-23T22:17:17.440869Z","end":"2026-06-23T22:17:17.749253Z","steps":["trace[1546373805] 'process raft request' (duration: 308.222625ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:17.749349Z","caller":"traceutil/trace.go:171","msg":"trace[321286439] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"308.255425ms","start":"2026-06-23T22:17:17.441079Z","end":"2026-06-23T22:17:17.749334Z","steps":["trace[321286439] 'process raft request' (duration: 308.04317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:17.749639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:17.44107Z","time spent":"308.333837ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:17.74971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:17.440843Z","time spent":"308.523831ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:17:17.749756Z","caller":"traceutil/trace.go:171","msg":"trace[47008429] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"308.098312ms","start":"2026-06-23T22:17:17.441078Z","end":"2026-06-23T22:17:17.749177Z","steps":["trace[47008429] 'process raft request' (duration: 308.065361ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:17.749849Z","caller":"traceutil/trace.go:171","msg":"trace[850248057] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"309.004212ms","start":"2026-06-23T22:17:17.440794Z","end":"2026-06-23T22:17:17.749798Z","steps":["trace[850248057] 'process raft request' (duration: 218.476501ms)","trace[850248057] 'compare' (duration: 89.718382ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:17:17.749927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:17.441063Z","time spent":"308.771977ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T22:17:17.750008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:17.440783Z","time spent":"309.137546ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T22:17:19.597582Z","caller":"traceutil/trace.go:171","msg":"trace[440141657] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"148.495821ms","start":"2026-06-23T22:17:19.449062Z","end":"2026-06-23T22:17:19.597557Z","steps":["trace[440141657] 'read index received' (duration: 148.252506ms)","trace[440141657] 'applied index is now lower than readState.Index' (duration: 242.385µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:19.597655Z","caller":"traceutil/trace.go:171","msg":"trace[235931161] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"155.645898ms","start":"2026-06-23T22:17:19.441964Z","end":"2026-06-23T22:17:19.59761Z","steps":["trace[235931161] 'process raft request' (duration: 155.422322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:19.597816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.719316ms","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-23T22:17:19.597859Z","caller":"traceutil/trace.go:171","msg":"trace[1886318229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1363; }","duration":"148.840519ms","start":"2026-06-23T22:17:19.449007Z","end":"2026-06-23T22:17:19.597847Z","steps":["trace[1886318229] 'agreement among raft nodes before linearized reading' (duration: 148.652435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:19.838239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.381656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:17:19.838409Z","caller":"traceutil/trace.go:171","msg":"trace[1242346628] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"236.130152ms","start":"2026-06-23T22:17:19.602259Z","end":"2026-06-23T22:17:19.838389Z","steps":["trace[1242346628] 'process raft request' (duration: 121.49231ms)","trace[1242346628] 'compare' (duration: 114.226632ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:20.224422Z","caller":"traceutil/trace.go:171","msg":"trace[1579144717] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"112.165584ms","start":"2026-06-23T22:17:20.112017Z","end":"2026-06-23T22:17:20.224182Z","steps":["trace[1579144717] 'process raft request' (duration: 98.926055ms)","trace[1579144717] 'compare' (duration: 12.969093ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:17:21.495378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.057381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:21.495468Z","caller":"traceutil/trace.go:171","msg":"trace[1067511474] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1371; }","duration":"848.204355ms","start":"2026-06-23T22:17:20.647241Z","end":"2026-06-23T22:17:21.495445Z","steps":["trace[1067511474] 'count revisions from in-memory index tree' (duration: 847.948299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:21.495513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:20.647211Z","time spent":"848.289446ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-23T22:17:21.495605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.927375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-23T22:17:21.495698Z","caller":"traceutil/trace.go:171","msg":"trace[538887212] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1371; }","duration":"368.076178ms","start":"2026-06-23T22:17:21.127595Z","end":"2026-06-23T22:17:21.495671Z","steps":["trace[538887212] 'range keys from in-memory index tree' (duration: 367.710179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:21.495738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:21.127562Z","time spent":"368.16759ms","remote":"127.0.0.1:36564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-23T22:17:21.495781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122942136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:21.495828Z","caller":"traceutil/trace.go:171","msg":"trace[1084334015] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1371; }","duration":"1.123057198s","start":"2026-06-23T22:17:20.372758Z","end":"2026-06-23T22:17:21.495815Z","steps":["trace[1084334015] 'count revisions from in-memory index tree' (duration: 1.122776072s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:21.495849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.231431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T22:17:21.495861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:20.372732Z","time spent":"1.12312027s","remote":"127.0.0.1:36424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-06-23T22:17:21.495882Z","caller":"traceutil/trace.go:171","msg":"trace[1598277655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"680.296443ms","start":"2026-06-23T22:17:20.815575Z","end":"2026-06-23T22:17:21.495872Z","steps":["trace[1598277655] 'range keys from in-memory index tree' (duration: 680.148729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:21.495908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T22:17:20.815555Z","time spent":"680.347163ms","remote":"127.0.0.1:36302","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-23T22:17:21.495931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.125486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:21.495978Z","caller":"traceutil/trace.go:171","msg":"trace[1587241892] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1371; }","duration":"140.214668ms","start":"2026-06-23T22:17:21.35575Z","end":"2026-06-23T22:17:21.495965Z","steps":["trace[1587241892] 'count revisions from in-memory index tree' (duration: 139.965652ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:21.860318Z","caller":"traceutil/trace.go:171","msg":"trace[170015876] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"133.783947ms","start":"2026-06-23T22:17:21.72651Z","end":"2026-06-23T22:17:21.860294Z","steps":["trace[170015876] 'read index received' (duration: 133.557092ms)","trace[170015876] 'applied index is now lower than readState.Index' (duration: 224.925µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:17:21.860365Z","caller":"traceutil/trace.go:171","msg":"trace[1559056520] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"152.423462ms","start":"2026-06-23T22:17:21.707908Z","end":"2026-06-23T22:17:21.860331Z","steps":["trace[1559056520] 'process raft request' (duration: 152.134216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:21.860556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.017413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T22:17:21.860639Z","caller":"traceutil/trace.go:171","msg":"trace[1965542968] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1375; }","duration":"134.158396ms","start":"2026-06-23T22:17:21.726463Z","end":"2026-06-23T22:17:21.860621Z","steps":["trace[1965542968] 'agreement among raft nodes before linearized reading' (duration: 133.969442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:17:21.941692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.940833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:17:21.941768Z","caller":"traceutil/trace.go:171","msg":"trace[1883856020] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1376; }","duration":"213.055935ms","start":"2026-06-23T22:17:21.728691Z","end":"2026-06-23T22:17:21.941747Z","steps":["trace[1883856020] 'agreement among raft nodes before linearized reading' (duration: 210.209398ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:38.223559Z","caller":"traceutil/trace.go:171","msg":"trace[984567446] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"136.716316ms","start":"2026-06-23T22:17:38.086812Z","end":"2026-06-23T22:17:38.223529Z","steps":["trace[984567446] 'process raft request' (duration: 136.563333ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:17:38.223588Z","caller":"traceutil/trace.go:171","msg":"trace[1358091534] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"136.778598ms","start":"2026-06-23T22:17:38.086721Z","end":"2026-06-23T22:17:38.2235Z","steps":["trace[1358091534] 'process raft request' (duration: 136.44847ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:18:56.755515Z","caller":"traceutil/trace.go:171","msg":"trace[1874352604] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"116.966045ms","start":"2026-06-23T22:18:56.638526Z","end":"2026-06-23T22:18:56.755492Z","steps":["trace[1874352604] 'process raft request' (duration: 116.250368ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:18:56.755717Z","caller":"traceutil/trace.go:171","msg":"trace[1435645448] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"116.236297ms","start":"2026-06-23T22:18:56.639448Z","end":"2026-06-23T22:18:56.755685Z","steps":["trace[1435645448] 'process raft request' (duration: 116.003542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:19:36.858218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.300736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T22:19:36.858452Z","caller":"traceutil/trace.go:171","msg":"trace[1188476793] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"210.578629ms","start":"2026-06-23T22:19:36.647822Z","end":"2026-06-23T22:19:36.8584Z","steps":["trace[1188476793] 'process raft request' (duration: 106.014547ms)","trace[1188476793] 'compare' (duration: 104.172373ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:19:37.184584Z","caller":"traceutil/trace.go:171","msg":"trace[571127766] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2092; }","duration":"136.965926ms","start":"2026-06-23T22:19:37.047588Z","end":"2026-06-23T22:19:37.184554Z","steps":["trace[571127766] 'read index received' (duration: 10.205051ms)","trace[571127766] 'applied index is now lower than readState.Index' (duration: 126.759605ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:19:37.184648Z","caller":"traceutil/trace.go:171","msg":"trace[1837405124] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"154.050628ms","start":"2026-06-23T22:19:37.03056Z","end":"2026-06-23T22:19:37.184611Z","steps":["trace[1837405124] 'process raft request' (duration: 152.522542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:19:37.184856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.432043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T22:19:37.184896Z","caller":"traceutil/trace.go:171","msg":"trace[962445005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2012; }","duration":"136.492564ms","start":"2026-06-23T22:19:37.048394Z","end":"2026-06-23T22:19:37.184887Z","steps":["trace[962445005] 'agreement among raft nodes before linearized reading' (duration: 136.31404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:19:37.184909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.307923ms","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-23T22:19:37.185075Z","caller":"traceutil/trace.go:171","msg":"trace[1943513439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2012; }","duration":"137.451397ms","start":"2026-06-23T22:19:37.047546Z","end":"2026-06-23T22:19:37.184997Z","steps":["trace[1943513439] 'agreement among raft nodes before linearized reading' (duration: 137.129699ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:19:37.44271Z","caller":"traceutil/trace.go:171","msg":"trace[720970479] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"253.941539ms","start":"2026-06-23T22:19:37.188742Z","end":"2026-06-23T22:19:37.442684Z","steps":["trace[720970479] 'process raft request' (duration: 223.147734ms)","trace[720970479] 'compare' (duration: 30.460288ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T22:19:37.442769Z","caller":"traceutil/trace.go:171","msg":"trace[527913736] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"253.480488ms","start":"2026-06-23T22:19:37.189276Z","end":"2026-06-23T22:19:37.442756Z","steps":["trace[527913736] 'process raft request' (duration: 253.339105ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:19:37.44276Z","caller":"traceutil/trace.go:171","msg":"trace[698034194] linearizableReadLoop","detail":"{readStateIndex:2097; appliedIndex:2094; }","duration":"226.093824ms","start":"2026-06-23T22:19:37.216645Z","end":"2026-06-23T22:19:37.442739Z","steps":["trace[698034194] 'read index received' (duration: 9.709489ms)","trace[698034194] 'applied index is now lower than readState.Index' (duration: 216.383375ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T22:19:37.442864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.216666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T22:19:37.442889Z","caller":"traceutil/trace.go:171","msg":"trace[697898346] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2015; }","duration":"226.279138ms","start":"2026-06-23T22:19:37.216605Z","end":"2026-06-23T22:19:37.442884Z","steps":["trace[697898346] 'agreement among raft nodes before linearized reading' (duration: 226.222897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:20:06.9351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.22572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T22:20:06.935185Z","caller":"traceutil/trace.go:171","msg":"trace[1111217478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2146; }","duration":"104.357663ms","start":"2026-06-23T22:20:06.830809Z","end":"2026-06-23T22:20:06.935167Z","steps":["trace[1111217478] 'range keys from in-memory index tree' (duration: 104.143697ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T22:20:07.351948Z","caller":"traceutil/trace.go:171","msg":"trace[867135993] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"103.548275ms","start":"2026-06-23T22:20:07.248365Z","end":"2026-06-23T22:20:07.351913Z","steps":["trace[867135993] 'process raft request' (duration: 101.714541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T22:20:42.202739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.042641ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"}