{"level":"warn","ts":"2026-03-11T01:44:03.537966Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T01:44:03.538046Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-03-11T01:44:03.538111Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T01:44:03.538119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-11T01:44:03.538138Z","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-03-11T01:44:03.5388Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-03-11T01:44:03.538869Z","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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-03-11T01:44:03.546239Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.181818ms"} {"level":"info","ts":"2026-03-11T01:44:03.55647Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-03-11T01:44:03.556525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-03-11T01:44:03.556546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-03-11T01:44:03.556559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-11T01:44:03.556568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-03-11T01:44:03.556602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-03-11T01:44:03.56531Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-11T01:44:03.570625Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-11T01:44:03.574725Z","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-03-11T01:44:03.578094Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-11T01:44:03.578215Z","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-03-11T01:44:03.578314Z","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-03-11T01:44:03.578327Z","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-03-11T01:44:03.578765Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-11T01:44:03.581433Z","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-03-11T01:44:03.581637Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-11T01:44:03.581667Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-11T01:44:03.581783Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-11T01:44:03.581799Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-11T01:44:03.586138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-03-11T01:44:03.586277Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-11T01:44:03.757015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-03-11T01:44:03.757074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-11T01:44:03.757119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-03-11T01:44:03.757149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-03-11T01:44:03.75716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-11T01:44:03.757185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-03-11T01:44:03.757198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-11T01:44:03.758418Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:44:03.759353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T01:44:03.759353Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-03-11T01:44:03.759566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T01:44:03.760039Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:44:03.760215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:44:03.760315Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:44:03.76141Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-11T01:44:03.761459Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-11T01:44:03.763306Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-03-11T01:44:03.769836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-11T01:44:29.114206Z","caller":"traceutil/trace.go:171","msg":"trace[1898297895] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"305.814912ms","start":"2026-03-11T01:44:28.808366Z","end":"2026-03-11T01:44:29.114181Z","steps":["trace[1898297895] 'process raft request' (duration: 305.661659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:29.114786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:28.808349Z","time spent":"305.967237ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:29.534279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.502809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-11T01:44:29.534266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.250735ms","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-03-11T01:44:29.534403Z","caller":"traceutil/trace.go:171","msg":"trace[686122377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"113.650412ms","start":"2026-03-11T01:44:29.420734Z","end":"2026-03-11T01:44:29.534384Z","steps":["trace[686122377] 'range keys from in-memory index tree' (duration: 113.373856ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:29.534419Z","caller":"traceutil/trace.go:171","msg":"trace[739149296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"122.45637ms","start":"2026-03-11T01:44:29.41194Z","end":"2026-03-11T01:44:29.534397Z","steps":["trace[739149296] 'range keys from in-memory index tree' (duration: 122.144312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:32.455499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.485857ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-11T01:44:33.022523Z","caller":"traceutil/trace.go:171","msg":"trace[2121240507] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"289.488109ms","start":"2026-03-11T01:44:32.733016Z","end":"2026-03-11T01:44:33.022504Z","steps":["trace[2121240507] 'process raft request' (duration: 289.365326ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:37.877437Z","caller":"traceutil/trace.go:171","msg":"trace[1886911625] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"277.350013ms","start":"2026-03-11T01:44:37.600061Z","end":"2026-03-11T01:44:37.877411Z","steps":["trace[1886911625] 'read index received' (duration: 277.085837ms)","trace[1886911625] 'applied index is now lower than readState.Index' (duration: 262.956µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:44:37.877663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.578998ms","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-03-11T01:44:37.877706Z","caller":"traceutil/trace.go:171","msg":"trace[1877351338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"277.668321ms","start":"2026-03-11T01:44:37.600027Z","end":"2026-03-11T01:44:37.877695Z","steps":["trace[1877351338] 'agreement among raft nodes before linearized reading' (duration: 277.528767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:37.877691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.88587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:44:37.877767Z","caller":"traceutil/trace.go:171","msg":"trace[2037460734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"177.010823ms","start":"2026-03-11T01:44:37.70074Z","end":"2026-03-11T01:44:37.877751Z","steps":["trace[2037460734] 'agreement among raft nodes before linearized reading' (duration: 176.89329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:37.877748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.962836ms","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-03-11T01:44:37.877825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.450766ms","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-03-11T01:44:37.877853Z","caller":"traceutil/trace.go:171","msg":"trace[546404540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"269.087489ms","start":"2026-03-11T01:44:37.608734Z","end":"2026-03-11T01:44:37.877821Z","steps":["trace[546404540] 'agreement among raft nodes before linearized reading' (duration: 268.893004ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:37.877925Z","caller":"traceutil/trace.go:171","msg":"trace[1667765076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"203.617349ms","start":"2026-03-11T01:44:37.674276Z","end":"2026-03-11T01:44:37.877894Z","steps":["trace[1667765076] 'agreement among raft nodes before linearized reading' (duration: 203.408454ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:37.877573Z","caller":"traceutil/trace.go:171","msg":"trace[771248305] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"281.45337ms","start":"2026-03-11T01:44:37.596086Z","end":"2026-03-11T01:44:37.87754Z","steps":["trace[771248305] 'process raft request' (duration: 281.149923ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:38.455608Z","caller":"traceutil/trace.go:171","msg":"trace[168029705] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"573.698933ms","start":"2026-03-11T01:44:37.881884Z","end":"2026-03-11T01:44:38.455583Z","steps":["trace[168029705] 'process raft request' (duration: 570.922998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:38.455738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:37.881862Z","time spent":"573.810896ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:38.460579Z","caller":"traceutil/trace.go:171","msg":"trace[961584254] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"578.339883ms","start":"2026-03-11T01:44:37.882218Z","end":"2026-03-11T01:44:38.460558Z","steps":["trace[961584254] 'process raft request' (duration: 578.308853ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:38.460661Z","caller":"traceutil/trace.go:171","msg":"trace[990094910] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"578.495437ms","start":"2026-03-11T01:44:37.882139Z","end":"2026-03-11T01:44:38.460634Z","steps":["trace[990094910] 'process raft request' (duration: 578.317403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:38.460697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:37.882211Z","time spent":"578.429765ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:38.460809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:37.882119Z","time spent":"578.61379ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:39.136895Z","caller":"traceutil/trace.go:171","msg":"trace[577575392] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"401.487153ms","start":"2026-03-11T01:44:38.735382Z","end":"2026-03-11T01:44:39.136869Z","steps":["trace[577575392] 'process raft request' (duration: 401.275668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:39.137063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:38.73537Z","time spent":"401.606935ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:39.884902Z","caller":"traceutil/trace.go:171","msg":"trace[1998408650] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"515.263139ms","start":"2026-03-11T01:44:39.369616Z","end":"2026-03-11T01:44:39.884879Z","steps":["trace[1998408650] 'process raft request' (duration: 515.119165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:39.885044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:39.369587Z","time spent":"515.385092ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:41.188599Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.303875127s","expected-duration":"1s"} {"level":"info","ts":"2026-03-11T01:44:41.189058Z","caller":"traceutil/trace.go:171","msg":"trace[444819286] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:540; }","duration":"1.589998136s","start":"2026-03-11T01:44:39.599031Z","end":"2026-03-11T01:44:41.18903Z","steps":["trace[444819286] 'read index received' (duration: 285.674599ms)","trace[444819286] 'applied index is now lower than readState.Index' (duration: 1.304322017s)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:41.189125Z","caller":"traceutil/trace.go:171","msg":"trace[259470524] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.689310072s","start":"2026-03-11T01:44:39.499786Z","end":"2026-03-11T01:44:41.189097Z","steps":["trace[259470524] 'process raft request' (duration: 1.689086227s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:41.189224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307034071s","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-03-11T01:44:41.189206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5901484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:44:41.189262Z","caller":"traceutil/trace.go:171","msg":"trace[2021531210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.307134274s","start":"2026-03-11T01:44:39.882118Z","end":"2026-03-11T01:44:41.189252Z","steps":["trace[2021531210] 'agreement among raft nodes before linearized reading' (duration: 1.307042461s)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:41.189311Z","caller":"traceutil/trace.go:171","msg":"trace[1794686362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"1.590276983s","start":"2026-03-11T01:44:39.598987Z","end":"2026-03-11T01:44:41.189264Z","steps":["trace[1794686362] 'agreement among raft nodes before linearized reading' (duration: 1.590121819s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:41.189359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:39.598963Z","time spent":"1.590387946s","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:44:41.189354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.677764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-11T01:44:41.189371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.272309ms","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-03-11T01:44:41.189384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:39.882088Z","time spent":"1.307285977s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-11T01:44:41.189406Z","caller":"traceutil/trace.go:171","msg":"trace[1944627525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"724.752726ms","start":"2026-03-11T01:44:40.464639Z","end":"2026-03-11T01:44:41.189392Z","steps":["trace[1944627525] 'agreement among raft nodes before linearized reading' (duration: 724.588612ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:41.189416Z","caller":"traceutil/trace.go:171","msg":"trace[481206319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"725.37184ms","start":"2026-03-11T01:44:40.464033Z","end":"2026-03-11T01:44:41.189405Z","steps":["trace[481206319] 'agreement among raft nodes before linearized reading' (duration: 725.205626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:41.189408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.601551ms","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-03-11T01:44:41.189443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:40.46463Z","time spent":"724.806188ms","remote":"127.0.0.1:44196","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-03-11T01:44:41.189473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:40.464008Z","time spent":"725.457092ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-11T01:44:41.189477Z","caller":"traceutil/trace.go:171","msg":"trace[1230212229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"729.717403ms","start":"2026-03-11T01:44:40.459742Z","end":"2026-03-11T01:44:41.18946Z","steps":["trace[1230212229] 'agreement among raft nodes before linearized reading' (duration: 729.5893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:41.189518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:40.459713Z","time spent":"729.796824ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T01:44:41.189837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:39.499742Z","time spent":"1.689455615s","remote":"127.0.0.1:44116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:41.699353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089690742,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:44:42.28217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.764527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:44:42.282442Z","caller":"traceutil/trace.go:171","msg":"trace[1469572868] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:542; }","duration":"1.083405961s","start":"2026-03-11T01:44:41.199019Z","end":"2026-03-11T01:44:42.282425Z","steps":["trace[1469572868] 'read index received' (duration: 874.98014ms)","trace[1469572868] 'applied index is now lower than readState.Index' (duration: 208.424991ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:42.282422Z","caller":"traceutil/trace.go:171","msg":"trace[786193758] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.089012674s","start":"2026-03-11T01:44:41.193381Z","end":"2026-03-11T01:44:42.282394Z","steps":["trace[786193758] 'process raft request' (duration: 880.604882ms)","trace[786193758] 'compare' (duration: 207.655764ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:42.282478Z","caller":"traceutil/trace.go:171","msg":"trace[1579711949] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.087959198s","start":"2026-03-11T01:44:41.194497Z","end":"2026-03-11T01:44:42.282456Z","steps":["trace[1579711949] 'process raft request' (duration: 1.087867036s)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:42.282478Z","caller":"traceutil/trace.go:171","msg":"trace[210428544] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.088982473s","start":"2026-03-11T01:44:41.193457Z","end":"2026-03-11T01:44:42.282439Z","steps":["trace[210428544] 'process raft request' (duration: 1.088820759s)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:42.282515Z","caller":"traceutil/trace.go:171","msg":"trace[1534462406] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.087225301s","start":"2026-03-11T01:44:41.195265Z","end":"2026-03-11T01:44:42.28249Z","steps":["trace[1534462406] 'process raft request' (duration: 1.087128759s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:42.28256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:41.193355Z","time spent":"1.089131826s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:42.282592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:41.194482Z","time spent":"1.08804306s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:42.282618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:41.193433Z","time spent":"1.089114125s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:44:42.282631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083604576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:44:42.282632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:41.195253Z","time spent":"1.087315193s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:42.282672Z","caller":"traceutil/trace.go:171","msg":"trace[1705369972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"1.083644737s","start":"2026-03-11T01:44:41.199013Z","end":"2026-03-11T01:44:42.282658Z","steps":["trace[1705369972] 'agreement among raft nodes before linearized reading' (duration: 1.083458942s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:42.282713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:41.198983Z","time spent":"1.083722549s","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-11T01:44:44.155031Z","caller":"traceutil/trace.go:171","msg":"trace[1208350460] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"176.193394ms","start":"2026-03-11T01:44:43.978813Z","end":"2026-03-11T01:44:44.155007Z","steps":["trace[1208350460] 'process raft request' (duration: 176.07025ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:02.720587Z","caller":"traceutil/trace.go:171","msg":"trace[43014551] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"938.007792ms","start":"2026-03-11T01:45:01.78256Z","end":"2026-03-11T01:45:02.720568Z","steps":["trace[43014551] 'process raft request' (duration: 937.886149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:02.720729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:01.782543Z","time spent":"938.104104ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:03.760502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.177548ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-11T01:45:03.760581Z","caller":"traceutil/trace.go:171","msg":"trace[1597337588] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"1.393814442s","start":"2026-03-11T01:45:02.366753Z","end":"2026-03-11T01:45:03.760568Z","steps":["trace[1597337588] 'read index received' (duration: 353.693149ms)","trace[1597337588] 'applied index is now lower than readState.Index' (duration: 1.040120243s)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:03.760731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37588091s","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-03-11T01:45:03.760752Z","caller":"traceutil/trace.go:171","msg":"trace[1314443869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"1.375979342s","start":"2026-03-11T01:45:02.384764Z","end":"2026-03-11T01:45:03.760744Z","steps":["trace[1314443869] 'agreement among raft nodes before linearized reading' (duration: 1.375866059s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:03.760774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:02.384745Z","time spent":"1.376024234s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-11T01:45:03.76076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.897134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:03.760825Z","caller":"traceutil/trace.go:171","msg":"trace[827889921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"161.011217ms","start":"2026-03-11T01:45:03.599794Z","end":"2026-03-11T01:45:03.760805Z","steps":["trace[827889921] 'agreement among raft nodes before linearized reading' (duration: 160.883624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:03.760999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37502234s","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-03-11T01:45:03.761049Z","caller":"traceutil/trace.go:171","msg":"trace[1022485286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.375114463s","start":"2026-03-11T01:45:02.385917Z","end":"2026-03-11T01:45:03.761032Z","steps":["trace[1022485286] 'agreement among raft nodes before linearized reading' (duration: 1.374910308s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:03.760978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375059511s","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-03-11T01:45:03.76113Z","caller":"traceutil/trace.go:171","msg":"trace[1915867452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.375234936s","start":"2026-03-11T01:45:02.385871Z","end":"2026-03-11T01:45:03.761106Z","steps":["trace[1915867452] 'agreement among raft nodes before linearized reading' (duration: 1.374970039s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:03.76117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:02.38586Z","time spent":"1.375298707s","remote":"127.0.0.1:44196","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-03-11T01:45:03.761714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39415765s","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-03-11T01:45:03.761807Z","caller":"traceutil/trace.go:171","msg":"trace[1885230184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"1.395079992s","start":"2026-03-11T01:45:02.366701Z","end":"2026-03-11T01:45:03.761781Z","steps":["trace[1885230184] 'agreement among raft nodes before linearized reading' (duration: 1.393921015s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:03.761862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:02.366683Z","time spent":"1.395163945s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T01:45:03.761257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:02.385911Z","time spent":"1.375333038s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-11T01:45:04.028687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.926617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:45:04.028864Z","caller":"traceutil/trace.go:171","msg":"trace[1356308425] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"262.599557ms","start":"2026-03-11T01:45:03.766239Z","end":"2026-03-11T01:45:04.028839Z","steps":["trace[1356308425] 'process raft request' (duration: 262.535515ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:04.028876Z","caller":"traceutil/trace.go:171","msg":"trace[349095292] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"262.683779ms","start":"2026-03-11T01:45:03.766148Z","end":"2026-03-11T01:45:04.028832Z","steps":["trace[349095292] 'process raft request' (duration: 126.534576ms)","trace[349095292] 'compare' (duration: 135.819375ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:04.02897Z","caller":"traceutil/trace.go:171","msg":"trace[342789099] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"262.024943ms","start":"2026-03-11T01:45:03.766912Z","end":"2026-03-11T01:45:04.028937Z","steps":["trace[342789099] 'process raft request' (duration: 261.89668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.877438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.322336ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:45:04.877511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.082428ms","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-03-11T01:45:04.877508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.505682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:45:04.877572Z","caller":"traceutil/trace.go:171","msg":"trace[1906707945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"152.18191ms","start":"2026-03-11T01:45:04.725368Z","end":"2026-03-11T01:45:04.87755Z","steps":["trace[1906707945] 'range keys from in-memory index tree' (duration: 151.935724ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:04.877613Z","caller":"traceutil/trace.go:171","msg":"trace[1873712030] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:722; }","duration":"162.693826ms","start":"2026-03-11T01:45:04.714897Z","end":"2026-03-11T01:45:04.877591Z","steps":["trace[1873712030] 'count revisions from in-memory index tree' (duration: 162.332589ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:04.877557Z","caller":"traceutil/trace.go:171","msg":"trace[797021741] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:722; }","duration":"528.447709ms","start":"2026-03-11T01:45:04.34909Z","end":"2026-03-11T01:45:04.877538Z","steps":["trace[797021741] 'range keys from in-memory index tree' (duration: 528.307026ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:05.09632Z","caller":"traceutil/trace.go:171","msg":"trace[1703132885] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"213.86645ms","start":"2026-03-11T01:45:04.882428Z","end":"2026-03-11T01:45:05.096295Z","steps":["trace[1703132885] 'process raft request' (duration: 213.528282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:06.17983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.819774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-11T01:45:06.179889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.199359ms","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-03-11T01:45:06.179916Z","caller":"traceutil/trace.go:171","msg":"trace[810797075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"146.975497ms","start":"2026-03-11T01:45:06.03292Z","end":"2026-03-11T01:45:06.179896Z","steps":["trace[810797075] 'range keys from in-memory index tree' (duration: 146.595859ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:06.179962Z","caller":"traceutil/trace.go:171","msg":"trace[218928474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"146.294061ms","start":"2026-03-11T01:45:06.033646Z","end":"2026-03-11T01:45:06.17994Z","steps":["trace[218928474] 'range keys from in-memory index tree' (duration: 146.047424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:06.180017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.740968ms","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-03-11T01:45:06.180112Z","caller":"traceutil/trace.go:171","msg":"trace[547355696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"145.904332ms","start":"2026-03-11T01:45:06.034183Z","end":"2026-03-11T01:45:06.180087Z","steps":["trace[547355696] 'range keys from in-memory index tree' (duration: 145.479812ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:06.32511Z","caller":"traceutil/trace.go:171","msg":"trace[1471207106] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"140.637609ms","start":"2026-03-11T01:45:06.184445Z","end":"2026-03-11T01:45:06.325082Z","steps":["trace[1471207106] 'process raft request' (duration: 101.543749ms)","trace[1471207106] 'compare' (duration: 38.828333ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:06.325092Z","caller":"traceutil/trace.go:171","msg":"trace[232548997] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"139.822828ms","start":"2026-03-11T01:45:06.185237Z","end":"2026-03-11T01:45:06.32506Z","steps":["trace[232548997] 'process raft request' (duration: 139.757426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:08.324919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.670756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:45:08.32501Z","caller":"traceutil/trace.go:171","msg":"trace[1976614140] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:736; }","duration":"222.83758ms","start":"2026-03-11T01:45:08.10215Z","end":"2026-03-11T01:45:08.324988Z","steps":["trace[1976614140] 'count revisions from in-memory index tree' (duration: 222.572854ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:08.785548Z","caller":"traceutil/trace.go:171","msg":"trace[1464324493] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"456.20409ms","start":"2026-03-11T01:45:08.329322Z","end":"2026-03-11T01:45:08.785527Z","steps":["trace[1464324493] 'read index received' (duration: 456.149649ms)","trace[1464324493] 'applied index is now lower than readState.Index' (duration: 53.331µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:08.785671Z","caller":"traceutil/trace.go:171","msg":"trace[1286022417] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"457.202603ms","start":"2026-03-11T01:45:08.328435Z","end":"2026-03-11T01:45:08.785638Z","steps":["trace[1286022417] 'process raft request' (duration: 456.965258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:08.785736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.390854ms","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-03-11T01:45:08.785763Z","caller":"traceutil/trace.go:171","msg":"trace[1940843612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"456.443205ms","start":"2026-03-11T01:45:08.329311Z","end":"2026-03-11T01:45:08.785754Z","steps":["trace[1940843612] 'agreement among raft nodes before linearized reading' (duration: 456.322032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:08.785788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:08.329306Z","time spent":"456.476536ms","remote":"127.0.0.1:44196","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-03-11T01:45:08.785831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:08.328388Z","time spent":"457.351707ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:45:08.80643Z","caller":"traceutil/trace.go:171","msg":"trace[811326870] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"475.148695ms","start":"2026-03-11T01:45:08.331269Z","end":"2026-03-11T01:45:08.806417Z","steps":["trace[811326870] 'process raft request' (duration: 475.043942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:08.806496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:08.331259Z","time spent":"475.208316ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:08.806579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.268962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:08.806666Z","caller":"traceutil/trace.go:171","msg":"trace[609029946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"209.423745ms","start":"2026-03-11T01:45:08.597221Z","end":"2026-03-11T01:45:08.806644Z","steps":["trace[609029946] 'agreement among raft nodes before linearized reading' (duration: 209.250921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:09.506564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.369201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:45:09.506652Z","caller":"traceutil/trace.go:171","msg":"trace[747601829] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"396.988407ms","start":"2026-03-11T01:45:09.109647Z","end":"2026-03-11T01:45:09.506635Z","steps":["trace[747601829] 'read index received' (duration: 28.471µs)","trace[747601829] 'applied index is now lower than readState.Index' (duration: 396.959065ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:09.506724Z","caller":"traceutil/trace.go:171","msg":"trace[1429595904] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"717.111796ms","start":"2026-03-11T01:45:08.789605Z","end":"2026-03-11T01:45:09.506716Z","steps":["trace[1429595904] 'process raft request' (duration: 59.506971ms)","trace[1429595904] 'compare' (duration: 657.246607ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:09.506759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:08.789588Z","time spent":"717.153287ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:09.506973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.311284ms","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-03-11T01:45:09.507042Z","caller":"traceutil/trace.go:171","msg":"trace[55399699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"397.424947ms","start":"2026-03-11T01:45:09.109599Z","end":"2026-03-11T01:45:09.507024Z","steps":["trace[55399699] 'agreement among raft nodes before linearized reading' (duration: 397.217363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:09.50708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:09.109579Z","time spent":"397.493279ms","remote":"127.0.0.1:44196","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-03-11T01:45:09.507107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.114434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:09.507166Z","caller":"traceutil/trace.go:171","msg":"trace[682054422] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:739; }","duration":"188.466422ms","start":"2026-03-11T01:45:09.318685Z","end":"2026-03-11T01:45:09.507152Z","steps":["trace[682054422] 'agreement among raft nodes before linearized reading' (duration: 188.117064ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:09.73262Z","caller":"traceutil/trace.go:171","msg":"trace[1990443520] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"132.635579ms","start":"2026-03-11T01:45:09.59996Z","end":"2026-03-11T01:45:09.732596Z","steps":["trace[1990443520] 'read index received' (duration: 132.445215ms)","trace[1990443520] 'applied index is now lower than readState.Index' (duration: 189.294µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:09.732776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.803583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:09.732815Z","caller":"traceutil/trace.go:171","msg":"trace[1914203309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"132.887945ms","start":"2026-03-11T01:45:09.599916Z","end":"2026-03-11T01:45:09.732804Z","steps":["trace[1914203309] 'agreement among raft nodes before linearized reading' (duration: 132.778533ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:09.732871Z","caller":"traceutil/trace.go:171","msg":"trace[2076621419] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"221.873908ms","start":"2026-03-11T01:45:09.51098Z","end":"2026-03-11T01:45:09.732854Z","steps":["trace[2076621419] 'process raft request' (duration: 221.475189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:10.298061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.322294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:45:10.298119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.114143ms","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-03-11T01:45:10.298146Z","caller":"traceutil/trace.go:171","msg":"trace[664396614] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:740; }","duration":"354.681043ms","start":"2026-03-11T01:45:09.943446Z","end":"2026-03-11T01:45:10.298127Z","steps":["trace[664396614] 'count revisions from in-memory index tree' (duration: 354.182611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:10.298208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:09.943386Z","time spent":"354.810125ms","remote":"127.0.0.1:44220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-11T01:45:10.298221Z","caller":"traceutil/trace.go:171","msg":"trace[247510198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"358.251366ms","start":"2026-03-11T01:45:09.939932Z","end":"2026-03-11T01:45:10.298183Z","steps":["trace[247510198] 'range keys from in-memory index tree' (duration: 357.652432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:10.298317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:09.939898Z","time spent":"358.366579ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-11T01:45:27.794247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:27.286225Z","time spent":"508.014543ms","remote":"127.0.0.1:48998","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-11T01:45:27.802866Z","caller":"traceutil/trace.go:171","msg":"trace[367248764] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"203.266073ms","start":"2026-03-11T01:45:27.599574Z","end":"2026-03-11T01:45:27.80284Z","steps":["trace[367248764] 'read index received' (duration: 203.043307ms)","trace[367248764] 'applied index is now lower than readState.Index' (duration: 221.596µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:27.802942Z","caller":"traceutil/trace.go:171","msg":"trace[723159217] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"432.446825ms","start":"2026-03-11T01:45:27.370446Z","end":"2026-03-11T01:45:27.802892Z","steps":["trace[723159217] 'process raft request' (duration: 432.22298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:27.80306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.447607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:27.803281Z","caller":"traceutil/trace.go:171","msg":"trace[873301717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"203.739285ms","start":"2026-03-11T01:45:27.59953Z","end":"2026-03-11T01:45:27.803269Z","steps":["trace[873301717] 'agreement among raft nodes before linearized reading' (duration: 203.457527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:27.803098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.902453ms","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-03-11T01:45:27.803428Z","caller":"traceutil/trace.go:171","msg":"trace[170433108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"183.252963ms","start":"2026-03-11T01:45:27.620152Z","end":"2026-03-11T01:45:27.803405Z","steps":["trace[170433108] 'agreement among raft nodes before linearized reading' (duration: 182.847512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:27.803563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:27.37042Z","time spent":"432.883396ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:28.492208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.881214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:45:28.492298Z","caller":"traceutil/trace.go:171","msg":"trace[1790823530] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"665.375166ms","start":"2026-03-11T01:45:27.826908Z","end":"2026-03-11T01:45:28.492283Z","steps":["trace[1790823530] 'read index received' (duration: 391.236196ms)","trace[1790823530] 'applied index is now lower than readState.Index' (duration: 274.13768ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:28.492394Z","caller":"traceutil/trace.go:171","msg":"trace[424206189] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"685.070921ms","start":"2026-03-11T01:45:27.807309Z","end":"2026-03-11T01:45:28.49238Z","steps":["trace[424206189] 'process raft request' (duration: 410.944501ms)","trace[424206189] 'compare' (duration: 273.772651ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:28.492449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:27.807295Z","time spent":"685.125302ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:28.492727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.810548ms","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-03-11T01:45:28.492767Z","caller":"traceutil/trace.go:171","msg":"trace[448387140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"665.873309ms","start":"2026-03-11T01:45:27.826884Z","end":"2026-03-11T01:45:28.492757Z","steps":["trace[448387140] 'agreement among raft nodes before linearized reading' (duration: 665.747716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:28.492796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:27.826871Z","time spent":"665.917289ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-11T01:45:28.709031Z","caller":"traceutil/trace.go:171","msg":"trace[171608754] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"211.525529ms","start":"2026-03-11T01:45:28.49742Z","end":"2026-03-11T01:45:28.708945Z","steps":["trace[171608754] 'process raft request' (duration: 173.617816ms)","trace[171608754] 'compare' (duration: 37.557584ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:30.823318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.971388ms","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-03-11T01:45:30.823402Z","caller":"traceutil/trace.go:171","msg":"trace[1629926605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"110.115441ms","start":"2026-03-11T01:45:30.713269Z","end":"2026-03-11T01:45:30.823384Z","steps":["trace[1629926605] 'range keys from in-memory index tree' (duration: 109.814235ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:30.946961Z","caller":"traceutil/trace.go:171","msg":"trace[1783482027] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"119.410691ms","start":"2026-03-11T01:45:30.827524Z","end":"2026-03-11T01:45:30.946935Z","steps":["trace[1783482027] 'process raft request' (duration: 119.255887ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:30.959622Z","caller":"traceutil/trace.go:171","msg":"trace[1395741646] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"108.636806ms","start":"2026-03-11T01:45:30.850971Z","end":"2026-03-11T01:45:30.959607Z","steps":["trace[1395741646] 'process raft request' (duration: 108.498143ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:38.155059Z","caller":"traceutil/trace.go:171","msg":"trace[1340546719] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"140.895976ms","start":"2026-03-11T01:45:38.014141Z","end":"2026-03-11T01:45:38.155037Z","steps":["trace[1340546719] 'process raft request' (duration: 140.701711ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:42.818792Z","caller":"traceutil/trace.go:171","msg":"trace[2052000099] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"149.688213ms","start":"2026-03-11T01:45:42.669088Z","end":"2026-03-11T01:45:42.818776Z","steps":["trace[2052000099] 'process raft request' (duration: 148.025744ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:43.493781Z","caller":"traceutil/trace.go:171","msg":"trace[552278089] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"367.437307ms","start":"2026-03-11T01:45:43.126324Z","end":"2026-03-11T01:45:43.493762Z","steps":["trace[552278089] 'read index received' (duration: 367.158619ms)","trace[552278089] 'applied index is now lower than readState.Index' (duration: 277.728µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:43.493844Z","caller":"traceutil/trace.go:171","msg":"trace[506279912] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"427.933829ms","start":"2026-03-11T01:45:43.065885Z","end":"2026-03-11T01:45:43.493819Z","steps":["trace[506279912] 'process raft request' (duration: 427.633301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:43.493943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.603261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-11T01:45:43.493976Z","caller":"traceutil/trace.go:171","msg":"trace[572237625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"367.664632ms","start":"2026-03-11T01:45:43.126301Z","end":"2026-03-11T01:45:43.493966Z","steps":["trace[572237625] 'agreement among raft nodes before linearized reading' (duration: 367.537199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:43.493987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:43.065875Z","time spent":"428.031422ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:43.494001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:43.126283Z","time spent":"367.712553ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-11T01:45:45.056688Z","caller":"traceutil/trace.go:171","msg":"trace[1100430325] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"233.891103ms","start":"2026-03-11T01:45:44.822767Z","end":"2026-03-11T01:45:45.056658Z","steps":["trace[1100430325] 'read index received' (duration: 233.736259ms)","trace[1100430325] 'applied index is now lower than readState.Index' (duration: 154.164µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:45.056948Z","caller":"traceutil/trace.go:171","msg":"trace[2101034036] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"382.382138ms","start":"2026-03-11T01:45:44.674558Z","end":"2026-03-11T01:45:45.05694Z","steps":["trace[2101034036] 'process raft request' (duration: 381.998509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:45.057023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:44.674529Z","time spent":"382.443979ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:45.057192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.403786ms","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-03-11T01:45:45.057262Z","caller":"traceutil/trace.go:171","msg":"trace[1799084016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"234.516118ms","start":"2026-03-11T01:45:44.822729Z","end":"2026-03-11T01:45:45.057245Z","steps":["trace[1799084016] 'agreement among raft nodes before linearized reading' (duration: 234.294433ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:45.725166Z","caller":"traceutil/trace.go:171","msg":"trace[1559144517] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"664.614909ms","start":"2026-03-11T01:45:45.06053Z","end":"2026-03-11T01:45:45.725145Z","steps":["trace[1559144517] 'process raft request' (duration: 649.397571ms)","trace[1559144517] 'compare' (duration: 14.902021ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:45.72527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:45.060507Z","time spent":"664.711991ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:45:45.729666Z","caller":"traceutil/trace.go:171","msg":"trace[629523330] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:920; }","duration":"490.824251ms","start":"2026-03-11T01:45:45.238825Z","end":"2026-03-11T01:45:45.729649Z","steps":["trace[629523330] 'read index received' (duration: 471.118587ms)","trace[629523330] 'applied index is now lower than readState.Index' (duration: 19.705034ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:45.729803Z","caller":"traceutil/trace.go:171","msg":"trace[980680828] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"668.277246ms","start":"2026-03-11T01:45:45.061519Z","end":"2026-03-11T01:45:45.729797Z","steps":["trace[980680828] 'process raft request' (duration: 668.081481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:45.729861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:45.061498Z","time spent":"668.318976ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:45.729978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.151538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:45.729996Z","caller":"traceutil/trace.go:171","msg":"trace[1943652649] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:888; }","duration":"491.209899ms","start":"2026-03-11T01:45:45.238779Z","end":"2026-03-11T01:45:45.729989Z","steps":["trace[1943652649] 'agreement among raft nodes before linearized reading' (duration: 491.173229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:45.73001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:45.238759Z","time spent":"491.24736ms","remote":"127.0.0.1:44270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:45:45.730136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.581329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-11T01:45:45.730184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.351561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:45.730201Z","caller":"traceutil/trace.go:171","msg":"trace[2068633840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"131.398542ms","start":"2026-03-11T01:45:45.598798Z","end":"2026-03-11T01:45:45.730196Z","steps":["trace[2068633840] 'agreement among raft nodes before linearized reading' (duration: 131.371482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:45.730225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.581189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-11T01:45:45.73024Z","caller":"traceutil/trace.go:171","msg":"trace[1312386656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"228.60721ms","start":"2026-03-11T01:45:45.501629Z","end":"2026-03-11T01:45:45.730236Z","steps":["trace[1312386656] 'agreement among raft nodes before linearized reading' (duration: 228.566519ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:45.730183Z","caller":"traceutil/trace.go:171","msg":"trace[1949768068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"228.661432ms","start":"2026-03-11T01:45:45.501506Z","end":"2026-03-11T01:45:45.730167Z","steps":["trace[1949768068] 'agreement among raft nodes before linearized reading' (duration: 228.528548ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:50.033769Z","caller":"traceutil/trace.go:171","msg":"trace[1946941108] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"256.338281ms","start":"2026-03-11T01:45:49.777409Z","end":"2026-03-11T01:45:50.033748Z","steps":["trace[1946941108] 'process raft request' (duration: 256.299571ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:50.034018Z","caller":"traceutil/trace.go:171","msg":"trace[1792235655] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"256.970527ms","start":"2026-03-11T01:45:49.777019Z","end":"2026-03-11T01:45:50.033989Z","steps":["trace[1792235655] 'process raft request' (duration: 256.640429ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:50.034549Z","caller":"traceutil/trace.go:171","msg":"trace[1128673669] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"258.39951ms","start":"2026-03-11T01:45:49.776118Z","end":"2026-03-11T01:45:50.034517Z","steps":["trace[1128673669] 'process raft request' (duration: 166.946299ms)","trace[1128673669] 'compare' (duration: 90.146741ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:56.225554Z","caller":"traceutil/trace.go:171","msg":"trace[1779740550] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"150.009909ms","start":"2026-03-11T01:45:56.07552Z","end":"2026-03-11T01:45:56.22553Z","steps":["trace[1779740550] 'process raft request' (duration: 149.882166ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:58.537446Z","caller":"traceutil/trace.go:171","msg":"trace[439821077] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"304.000834ms","start":"2026-03-11T01:45:58.233425Z","end":"2026-03-11T01:45:58.537426Z","steps":["trace[439821077] 'process raft request' (duration: 303.283086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:58.537709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:58.233398Z","time spent":"304.180927ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:59.099909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089691646,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:45:59.414146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.582871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:45:59.414263Z","caller":"traceutil/trace.go:171","msg":"trace[1296475702] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"815.069219ms","start":"2026-03-11T01:45:58.599177Z","end":"2026-03-11T01:45:59.414246Z","steps":["trace[1296475702] 'read index received' (duration: 574.350115ms)","trace[1296475702] 'applied index is now lower than readState.Index' (duration: 240.717694ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:59.414307Z","caller":"traceutil/trace.go:171","msg":"trace[1163662869] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"881.799079ms","start":"2026-03-11T01:45:58.532458Z","end":"2026-03-11T01:45:59.414257Z","steps":["trace[1163662869] 'process raft request' (duration: 641.015993ms)","trace[1163662869] 'compare' (duration: 240.343405ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:45:59.414433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.814397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:59.414473Z","caller":"traceutil/trace.go:171","msg":"trace[1524797279] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:925; }","duration":"767.887319ms","start":"2026-03-11T01:45:58.646573Z","end":"2026-03-11T01:45:59.41446Z","steps":["trace[1524797279] 'agreement among raft nodes before linearized reading' (duration: 767.816917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:59.414452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.256623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:45:59.414485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:58.532435Z","time spent":"881.943053ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:59.414515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:58.646557Z","time spent":"767.94868ms","remote":"127.0.0.1:37924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-11T01:45:59.414525Z","caller":"traceutil/trace.go:171","msg":"trace[977923347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"815.381816ms","start":"2026-03-11T01:45:58.599125Z","end":"2026-03-11T01:45:59.414507Z","steps":["trace[977923347] 'agreement among raft nodes before linearized reading' (duration: 815.240793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:59.414569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:58.599104Z","time spent":"815.456948ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:45:59.414805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.652632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:45:59.41484Z","caller":"traceutil/trace.go:171","msg":"trace[1128265084] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:925; }","duration":"418.699372ms","start":"2026-03-11T01:45:58.996133Z","end":"2026-03-11T01:45:59.414832Z","steps":["trace[1128265084] 'agreement among raft nodes before linearized reading' (duration: 418.644211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:59.414847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.504882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:45:59.414868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:58.996125Z","time spent":"418.736463ms","remote":"127.0.0.1:37902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-03-11T01:45:59.414889Z","caller":"traceutil/trace.go:171","msg":"trace[868717905] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:925; }","duration":"476.589334ms","start":"2026-03-11T01:45:58.938288Z","end":"2026-03-11T01:45:59.414877Z","steps":["trace[868717905] 'agreement among raft nodes before linearized reading' (duration: 476.504663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:59.414944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:58.938274Z","time spent":"476.655526ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:45:59.414946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.069103ms","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-03-11T01:45:59.414999Z","caller":"traceutil/trace.go:171","msg":"trace[1470103020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"278.166295ms","start":"2026-03-11T01:45:59.136818Z","end":"2026-03-11T01:45:59.414984Z","steps":["trace[1470103020] 'agreement among raft nodes before linearized reading' (duration: 278.023642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:59.415227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.281323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:45:59.415254Z","caller":"traceutil/trace.go:171","msg":"trace[1313196583] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:925; }","duration":"410.322724ms","start":"2026-03-11T01:45:59.004925Z","end":"2026-03-11T01:45:59.415248Z","steps":["trace[1313196583] 'agreement among raft nodes before linearized reading' (duration: 409.838213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:59.41528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:59.004921Z","time spent":"410.350645ms","remote":"127.0.0.1:44054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:46:00.202776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.447205ms","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-03-11T01:46:00.202941Z","caller":"traceutil/trace.go:171","msg":"trace[441996806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"106.700921ms","start":"2026-03-11T01:46:00.096187Z","end":"2026-03-11T01:46:00.202888Z","steps":["trace[441996806] 'range keys from in-memory index tree' (duration: 105.675867ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:02.447975Z","caller":"traceutil/trace.go:171","msg":"trace[913563475] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"329.710608ms","start":"2026-03-11T01:46:02.118238Z","end":"2026-03-11T01:46:02.447948Z","steps":["trace[913563475] 'process raft request' (duration: 238.799669ms)","trace[913563475] 'compare' (duration: 90.664013ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:02.448129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:02.118212Z","time spent":"329.830222ms","remote":"127.0.0.1:44212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:02.448223Z","caller":"traceutil/trace.go:171","msg":"trace[175769640] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"327.565427ms","start":"2026-03-11T01:46:02.120637Z","end":"2026-03-11T01:46:02.448202Z","steps":["trace[175769640] 'process raft request' (duration: 327.173728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:02.448352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:02.120623Z","time spent":"327.65789ms","remote":"127.0.0.1:37902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:02.486504Z","caller":"traceutil/trace.go:171","msg":"trace[1433317357] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:988; }","duration":"177.967757ms","start":"2026-03-11T01:46:02.308512Z","end":"2026-03-11T01:46:02.48648Z","steps":["trace[1433317357] 'read index received' (duration: 48.557572ms)","trace[1433317357] 'applied index is now lower than readState.Index' (duration: 129.408965ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:02.486742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.229053ms","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-03-11T01:46:02.486805Z","caller":"traceutil/trace.go:171","msg":"trace[1332436537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"178.329176ms","start":"2026-03-11T01:46:02.308463Z","end":"2026-03-11T01:46:02.486792Z","steps":["trace[1332436537] 'agreement among raft nodes before linearized reading' (duration: 178.168362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:02.486904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.577828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-11T01:46:02.486952Z","caller":"traceutil/trace.go:171","msg":"trace[1031969847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"177.66754ms","start":"2026-03-11T01:46:02.309273Z","end":"2026-03-11T01:46:02.486941Z","steps":["trace[1031969847] 'agreement among raft nodes before linearized reading' (duration: 177.462515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:02.65257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.82557ms","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-03-11T01:46:02.652747Z","caller":"traceutil/trace.go:171","msg":"trace[1462118171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"103.051985ms","start":"2026-03-11T01:46:02.549665Z","end":"2026-03-11T01:46:02.652717Z","steps":["trace[1462118171] 'range keys from in-memory index tree' (duration: 102.44139ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:02.762302Z","caller":"traceutil/trace.go:171","msg":"trace[727312909] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"106.310001ms","start":"2026-03-11T01:46:02.655946Z","end":"2026-03-11T01:46:02.762256Z","steps":["trace[727312909] 'process raft request' (duration: 106.027144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:03.240521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.617964ms","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-03-11T01:46:03.240607Z","caller":"traceutil/trace.go:171","msg":"trace[420151461] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:956; }","duration":"136.754007ms","start":"2026-03-11T01:46:03.103833Z","end":"2026-03-11T01:46:03.240587Z","steps":["trace[420151461] 'count revisions from in-memory index tree' (duration: 136.539322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:03.240783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.995315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:46:03.240842Z","caller":"traceutil/trace.go:171","msg":"trace[1917567942] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:956; }","duration":"208.104727ms","start":"2026-03-11T01:46:03.032721Z","end":"2026-03-11T01:46:03.240826Z","steps":["trace[1917567942] 'count revisions from in-memory index tree' (duration: 207.925703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:04.099693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089691709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:46:04.60062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089691709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:46:05.101277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089691709,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:46:05.195291Z","caller":"traceutil/trace.go:171","msg":"trace[1170448496] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"1.596125358s","start":"2026-03-11T01:46:03.59914Z","end":"2026-03-11T01:46:05.195265Z","steps":["trace[1170448496] 'read index received' (duration: 1.595924783s)","trace[1170448496] 'applied index is now lower than readState.Index' (duration: 199.545µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:05.195426Z","caller":"traceutil/trace.go:171","msg":"trace[1008539591] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.711300858s","start":"2026-03-11T01:46:03.484115Z","end":"2026-03-11T01:46:05.195416Z","steps":["trace[1008539591] 'process raft request' (duration: 1.710912549s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.195539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:03.484099Z","time spent":"1.711363069s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:05.195739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262301722s","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-03-11T01:46:05.195823Z","caller":"traceutil/trace.go:171","msg":"trace[500682037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"1.262438105s","start":"2026-03-11T01:46:03.933366Z","end":"2026-03-11T01:46:05.195804Z","steps":["trace[500682037] 'agreement among raft nodes before linearized reading' (duration: 1.26222441s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.196048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:03.933343Z","time spent":"1.262695793s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-11T01:46:05.196153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.869693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:46:05.196223Z","caller":"traceutil/trace.go:171","msg":"trace[1356752434] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:957; }","duration":"536.956245ms","start":"2026-03-11T01:46:04.659233Z","end":"2026-03-11T01:46:05.196189Z","steps":["trace[1356752434] 'agreement among raft nodes before linearized reading' (duration: 536.878833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.196264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:04.659211Z","time spent":"537.043727ms","remote":"127.0.0.1:44212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:46:05.196275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.522579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:46:05.196309Z","caller":"traceutil/trace.go:171","msg":"trace[407862075] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:957; }","duration":"622.601891ms","start":"2026-03-11T01:46:04.573697Z","end":"2026-03-11T01:46:05.196299Z","steps":["trace[407862075] 'agreement among raft nodes before linearized reading' (duration: 622.533839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.196347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:04.573677Z","time spent":"622.662712ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:46:05.196436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597308347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:05.196472Z","caller":"traceutil/trace.go:171","msg":"trace[151436005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"1.597384828s","start":"2026-03-11T01:46:03.599077Z","end":"2026-03-11T01:46:05.196462Z","steps":["trace[151436005] 'agreement among raft nodes before linearized reading' (duration: 1.597335827s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.196499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:03.599054Z","time spent":"1.597439429s","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:46:05.196547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.364812ms","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-03-11T01:46:05.196581Z","caller":"traceutil/trace.go:171","msg":"trace[1641162190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"664.417364ms","start":"2026-03-11T01:46:04.532155Z","end":"2026-03-11T01:46:05.196572Z","steps":["trace[1641162190] 'agreement among raft nodes before linearized reading' (duration: 664.323192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.196608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:04.532145Z","time spent":"664.457915ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-11T01:46:05.19681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.048654ms","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-03-11T01:46:05.19685Z","caller":"traceutil/trace.go:171","msg":"trace[1463746586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"692.128225ms","start":"2026-03-11T01:46:04.504713Z","end":"2026-03-11T01:46:05.196841Z","steps":["trace[1463746586] 'agreement among raft nodes before linearized reading' (duration: 692.021193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.196878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:04.504691Z","time spent":"692.180747ms","remote":"127.0.0.1:44196","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-03-11T01:46:05.197077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.058783ms","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-03-11T01:46:05.197194Z","caller":"traceutil/trace.go:171","msg":"trace[2121228653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"431.217247ms","start":"2026-03-11T01:46:04.765962Z","end":"2026-03-11T01:46:05.197179Z","steps":["trace[2121228653] 'agreement among raft nodes before linearized reading' (duration: 430.591053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.197288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:04.765942Z","time spent":"431.335099ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T01:46:05.705154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089691713,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:46:05.775984Z","caller":"traceutil/trace.go:171","msg":"trace[234270669] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"575.036921ms","start":"2026-03-11T01:46:05.200921Z","end":"2026-03-11T01:46:05.775958Z","steps":["trace[234270669] 'process raft request' (duration: 520.565739ms)","trace[234270669] 'compare' (duration: 53.952109ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:05.776213Z","caller":"traceutil/trace.go:171","msg":"trace[1515420686] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"574.746495ms","start":"2026-03-11T01:46:05.201435Z","end":"2026-03-11T01:46:05.776182Z","steps":["trace[1515420686] 'process raft request' (duration: 574.548321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.776371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:05.201429Z","time spent":"574.852028ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:05.776038Z","caller":"traceutil/trace.go:171","msg":"trace[373821637] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:995; }","duration":"571.323484ms","start":"2026-03-11T01:46:05.204698Z","end":"2026-03-11T01:46:05.776022Z","steps":["trace[373821637] 'read index received' (duration: 516.79935ms)","trace[373821637] 'applied index is now lower than readState.Index' (duration: 54.523564ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:05.776122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.406896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:05.776561Z","caller":"traceutil/trace.go:171","msg":"trace[326895699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"571.857746ms","start":"2026-03-11T01:46:05.204678Z","end":"2026-03-11T01:46:05.776536Z","steps":["trace[326895699] 'agreement among raft nodes before linearized reading' (duration: 571.398465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.776596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.913873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:46:05.77663Z","caller":"traceutil/trace.go:171","msg":"trace[325545614] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:961; }","duration":"289.997664ms","start":"2026-03-11T01:46:05.486623Z","end":"2026-03-11T01:46:05.776621Z","steps":["trace[325545614] 'agreement among raft nodes before linearized reading' (duration: 289.929012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.776651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:05.204671Z","time spent":"571.952518ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-11T01:46:05.776148Z","caller":"traceutil/trace.go:171","msg":"trace[1635518412] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"574.580031ms","start":"2026-03-11T01:46:05.201562Z","end":"2026-03-11T01:46:05.776142Z","steps":["trace[1635518412] 'process raft request' (duration: 574.441137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.776877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:05.201545Z","time spent":"575.291548ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:05.776114Z","caller":"traceutil/trace.go:171","msg":"trace[1079774017] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"574.876598ms","start":"2026-03-11T01:46:05.20121Z","end":"2026-03-11T01:46:05.776087Z","steps":["trace[1079774017] 'process raft request' (duration: 574.727745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:05.777023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:05.20119Z","time spent":"575.769229ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:05.777042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:05.200903Z","time spent":"575.880451ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:07.839527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.976991ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-11T01:46:07.839596Z","caller":"traceutil/trace.go:171","msg":"trace[778614732] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"921.318419ms","start":"2026-03-11T01:46:06.918265Z","end":"2026-03-11T01:46:07.839583Z","steps":["trace[778614732] 'read index received' (duration: 425.256166ms)","trace[778614732] 'applied index is now lower than readState.Index' (duration: 496.061503ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:07.839638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:06.872743Z","time spent":"966.893281ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-11T01:46:07.839844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.575456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:07.839869Z","caller":"traceutil/trace.go:171","msg":"trace[330175108] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:963; }","duration":"921.631666ms","start":"2026-03-11T01:46:06.91823Z","end":"2026-03-11T01:46:07.839862Z","steps":["trace[330175108] 'agreement among raft nodes before linearized reading' (duration: 921.578025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:07.839885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:06.918214Z","time spent":"921.667888ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-11T01:46:08.498728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.894581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:46:08.499044Z","caller":"traceutil/trace.go:171","msg":"trace[1210008074] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"924.444022ms","start":"2026-03-11T01:46:07.574576Z","end":"2026-03-11T01:46:08.49902Z","steps":["trace[1210008074] 'process raft request' (duration: 685.182042ms)","trace[1210008074] 'compare' (duration: 238.768168ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:08.49913Z","caller":"traceutil/trace.go:171","msg":"trace[1926319823] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"659.492969ms","start":"2026-03-11T01:46:07.839609Z","end":"2026-03-11T01:46:08.499102Z","steps":["trace[1926319823] 'read index received' (duration: 420.268979ms)","trace[1926319823] 'applied index is now lower than readState.Index' (duration: 239.22065ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:08.499166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.574556Z","time spent":"924.540755ms","remote":"127.0.0.1:44048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T01:46:08.4992Z","caller":"traceutil/trace.go:171","msg":"trace[497970394] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"658.882614ms","start":"2026-03-11T01:46:07.840311Z","end":"2026-03-11T01:46:08.499194Z","steps":["trace[497970394] 'process raft request' (duration: 658.68929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.499255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.840298Z","time spent":"658.916446ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:08.499305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.390334ms","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-03-11T01:46:08.499376Z","caller":"traceutil/trace.go:171","msg":"trace[1071932336] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"655.806731ms","start":"2026-03-11T01:46:07.843543Z","end":"2026-03-11T01:46:08.49935Z","steps":["trace[1071932336] 'process raft request' (duration: 655.533355ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.499417Z","caller":"traceutil/trace.go:171","msg":"trace[136546064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"718.509847ms","start":"2026-03-11T01:46:07.780862Z","end":"2026-03-11T01:46:08.499372Z","steps":["trace[136546064] 'agreement among raft nodes before linearized reading' (duration: 718.267932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.499489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.780844Z","time spent":"718.63317ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-11T01:46:08.499541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.519834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:46:08.499557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.652491ms","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-03-11T01:46:08.499584Z","caller":"traceutil/trace.go:171","msg":"trace[1096143583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"715.696471ms","start":"2026-03-11T01:46:07.783881Z","end":"2026-03-11T01:46:08.499578Z","steps":["trace[1096143583] 'agreement among raft nodes before linearized reading' (duration: 715.288862ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.499587Z","caller":"traceutil/trace.go:171","msg":"trace[1063575711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"799.611326ms","start":"2026-03-11T01:46:07.699964Z","end":"2026-03-11T01:46:08.499576Z","steps":["trace[1063575711] 'agreement among raft nodes before linearized reading' (duration: 799.223997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.499599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.783874Z","time spent":"715.722142ms","remote":"127.0.0.1:44196","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-03-11T01:46:08.499626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.699946Z","time spent":"799.671757ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:46:08.499511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.843537Z","time spent":"655.910874ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-11T01:46:08.499644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297173293s","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-03-11T01:46:08.499685Z","caller":"traceutil/trace.go:171","msg":"trace[760697732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"1.297258515s","start":"2026-03-11T01:46:07.202415Z","end":"2026-03-11T01:46:08.499673Z","steps":["trace[760697732] 'agreement among raft nodes before linearized reading' (duration: 1.296783774s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.499731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.20239Z","time spent":"1.297322486s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-11T01:46:08.500363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.67951ms","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-03-11T01:46:08.50042Z","caller":"traceutil/trace.go:171","msg":"trace[430357568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"718.756902ms","start":"2026-03-11T01:46:07.781652Z","end":"2026-03-11T01:46:08.500409Z","steps":["trace[430357568] 'agreement among raft nodes before linearized reading' (duration: 717.526374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.500524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.781641Z","time spent":"718.871865ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-11T01:46:08.499985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.07254ms","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-03-11T01:46:08.502078Z","caller":"traceutil/trace.go:171","msg":"trace[466739400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"721.18778ms","start":"2026-03-11T01:46:07.780864Z","end":"2026-03-11T01:46:08.502052Z","steps":["trace[466739400] 'agreement among raft nodes before linearized reading' (duration: 718.264632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.502204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:07.780851Z","time spent":"721.334744ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-11T01:46:08.668399Z","caller":"traceutil/trace.go:171","msg":"trace[1209979491] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"164.662355ms","start":"2026-03-11T01:46:08.503712Z","end":"2026-03-11T01:46:08.668374Z","steps":["trace[1209979491] 'process raft request' (duration: 164.525001ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.718375Z","caller":"traceutil/trace.go:171","msg":"trace[2133316172] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"213.867222ms","start":"2026-03-11T01:46:08.504481Z","end":"2026-03-11T01:46:08.718348Z","steps":["trace[2133316172] 'process raft request' (duration: 213.636136ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.718492Z","caller":"traceutil/trace.go:171","msg":"trace[335085621] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"212.725176ms","start":"2026-03-11T01:46:08.505758Z","end":"2026-03-11T01:46:08.718483Z","steps":["trace[335085621] 'process raft request' (duration: 212.558432ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.71838Z","caller":"traceutil/trace.go:171","msg":"trace[2133904043] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"213.550575ms","start":"2026-03-11T01:46:08.504804Z","end":"2026-03-11T01:46:08.718354Z","steps":["trace[2133904043] 'process raft request' (duration: 213.453352ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.718766Z","caller":"traceutil/trace.go:171","msg":"trace[1442129845] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"211.905327ms","start":"2026-03-11T01:46:08.506844Z","end":"2026-03-11T01:46:08.71875Z","steps":["trace[1442129845] 'process raft request' (duration: 211.60768ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.718811Z","caller":"traceutil/trace.go:171","msg":"trace[898944633] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"211.282611ms","start":"2026-03-11T01:46:08.507511Z","end":"2026-03-11T01:46:08.718794Z","steps":["trace[898944633] 'process raft request' (duration: 211.216449ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.718861Z","caller":"traceutil/trace.go:171","msg":"trace[1186334237] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"211.857955ms","start":"2026-03-11T01:46:08.50699Z","end":"2026-03-11T01:46:08.718848Z","steps":["trace[1186334237] 'process raft request' (duration: 211.675401ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:08.718874Z","caller":"traceutil/trace.go:171","msg":"trace[802098735] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1005; }","duration":"213.762749ms","start":"2026-03-11T01:46:08.50509Z","end":"2026-03-11T01:46:08.718853Z","steps":["trace[802098735] 'read index received' (duration: 163.2114ms)","trace[802098735] 'applied index is now lower than readState.Index' (duration: 50.550159ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:08.718989Z","caller":"traceutil/trace.go:171","msg":"trace[927955460] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"211.448725ms","start":"2026-03-11T01:46:08.507531Z","end":"2026-03-11T01:46:08.718979Z","steps":["trace[927955460] 'process raft request' (duration: 211.23536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.719053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.371879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:08.719096Z","caller":"traceutil/trace.go:171","msg":"trace[253825280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"122.463462ms","start":"2026-03-11T01:46:08.596623Z","end":"2026-03-11T01:46:08.719086Z","steps":["trace[253825280] 'agreement among raft nodes before linearized reading' (duration: 122.365649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:08.719211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.101697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-11T01:46:08.719269Z","caller":"traceutil/trace.go:171","msg":"trace[162791740] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:974; }","duration":"214.18483ms","start":"2026-03-11T01:46:08.505073Z","end":"2026-03-11T01:46:08.719258Z","steps":["trace[162791740] 'agreement among raft nodes before linearized reading' (duration: 214.060407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:10.099884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673541199089691745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:46:10.126917Z","caller":"traceutil/trace.go:171","msg":"trace[1630697796] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"527.464912ms","start":"2026-03-11T01:46:09.59943Z","end":"2026-03-11T01:46:10.126895Z","steps":["trace[1630697796] 'read index received' (duration: 527.337579ms)","trace[1630697796] 'applied index is now lower than readState.Index' (duration: 126.413µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:10.126953Z","caller":"traceutil/trace.go:171","msg":"trace[508234113] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"537.323244ms","start":"2026-03-11T01:46:09.589611Z","end":"2026-03-11T01:46:10.126934Z","steps":["trace[508234113] 'process raft request' (duration: 537.171211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:10.127025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.588746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:10.127055Z","caller":"traceutil/trace.go:171","msg":"trace[916072407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"527.658027ms","start":"2026-03-11T01:46:09.599388Z","end":"2026-03-11T01:46:10.127046Z","steps":["trace[916072407] 'agreement among raft nodes before linearized reading' (duration: 527.594215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:10.127088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:09.599377Z","time spent":"527.705018ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:46:10.127076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:09.589591Z","time spent":"537.408306ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:11.243568Z","caller":"traceutil/trace.go:171","msg":"trace[1579690264] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"516.645947ms","start":"2026-03-11T01:46:10.726899Z","end":"2026-03-11T01:46:11.243545Z","steps":["trace[1579690264] 'process raft request' (duration: 516.492203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:11.243751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:10.726874Z","time spent":"516.78921ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:11.614752Z","caller":"traceutil/trace.go:171","msg":"trace[409777546] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1014; }","duration":"643.292538ms","start":"2026-03-11T01:46:10.971441Z","end":"2026-03-11T01:46:11.614734Z","steps":["trace[409777546] 'read index received' (duration: 271.9693ms)","trace[409777546] 'applied index is now lower than readState.Index' (duration: 371.322138ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:11.614861Z","caller":"traceutil/trace.go:171","msg":"trace[256208010] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"886.166872ms","start":"2026-03-11T01:46:10.728681Z","end":"2026-03-11T01:46:11.614848Z","steps":["trace[256208010] 'process raft request' (duration: 885.900165ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:11.61486Z","caller":"traceutil/trace.go:171","msg":"trace[1857133597] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"876.27718ms","start":"2026-03-11T01:46:10.738564Z","end":"2026-03-11T01:46:11.614841Z","steps":["trace[1857133597] 'process raft request' (duration: 876.131717ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:11.614878Z","caller":"traceutil/trace.go:171","msg":"trace[1507005122] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"885.638309ms","start":"2026-03-11T01:46:10.729204Z","end":"2026-03-11T01:46:11.614843Z","steps":["trace[1507005122] 'process raft request' (duration: 885.458925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:11.614955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:10.728662Z","time spent":"886.227724ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:11.61508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:10.729186Z","time spent":"885.777253ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:11.615137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.699697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:11.615171Z","caller":"traceutil/trace.go:171","msg":"trace[1844468987] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:980; }","duration":"643.771269ms","start":"2026-03-11T01:46:10.971389Z","end":"2026-03-11T01:46:11.61516Z","steps":["trace[1844468987] 'agreement among raft nodes before linearized reading' (duration: 643.702677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:11.615207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:10.97137Z","time spent":"643.82962ms","remote":"127.0.0.1:44166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:46:11.615147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:10.738543Z","time spent":"876.533986ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:11.614824Z","caller":"traceutil/trace.go:171","msg":"trace[1331370044] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"887.515434ms","start":"2026-03-11T01:46:10.727281Z","end":"2026-03-11T01:46:11.614797Z","steps":["trace[1331370044] 'process raft request' (duration: 886.386137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:11.615598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:10.727261Z","time spent":"888.284532ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:12.393223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.638566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:46:12.393342Z","caller":"traceutil/trace.go:171","msg":"trace[1141610913] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"485.450463ms","start":"2026-03-11T01:46:11.907869Z","end":"2026-03-11T01:46:12.39332Z","steps":["trace[1141610913] 'read index received' (duration: 362.584652ms)","trace[1141610913] 'applied index is now lower than readState.Index' (duration: 122.863371ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:12.393433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.551926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:46:12.393465Z","caller":"traceutil/trace.go:171","msg":"trace[591667972] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:981; }","duration":"485.628437ms","start":"2026-03-11T01:46:11.907827Z","end":"2026-03-11T01:46:12.393455Z","steps":["trace[591667972] 'agreement among raft nodes before linearized reading' (duration: 485.558306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:12.393503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:11.907807Z","time spent":"485.685809ms","remote":"127.0.0.1:44116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-03-11T01:46:12.393832Z","caller":"traceutil/trace.go:171","msg":"trace[197198952] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"685.096402ms","start":"2026-03-11T01:46:11.708451Z","end":"2026-03-11T01:46:12.393548Z","steps":["trace[197198952] 'process raft request' (duration: 562.052516ms)","trace[197198952] 'compare' (duration: 122.496273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:12.394651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:11.708422Z","time spent":"685.664904ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:12.541938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.941722ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-11T01:46:15.760462Z","caller":"traceutil/trace.go:171","msg":"trace[1872338722] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"161.584933ms","start":"2026-03-11T01:46:15.598851Z","end":"2026-03-11T01:46:15.760436Z","steps":["trace[1872338722] 'read index received' (duration: 161.379519ms)","trace[1872338722] 'applied index is now lower than readState.Index' (duration: 204.404µs)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:15.76061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.739247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:15.760649Z","caller":"traceutil/trace.go:171","msg":"trace[983106496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"161.829029ms","start":"2026-03-11T01:46:15.59881Z","end":"2026-03-11T01:46:15.760639Z","steps":["trace[983106496] 'agreement among raft nodes before linearized reading' (duration: 161.748417ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:15.760548Z","caller":"traceutil/trace.go:171","msg":"trace[1863661243] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"500.415747ms","start":"2026-03-11T01:46:15.260106Z","end":"2026-03-11T01:46:15.760522Z","steps":["trace[1863661243] 'process raft request' (duration: 500.179252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:15.761325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:15.260083Z","time spent":"500.654592ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:16.326354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.74893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:46:16.326455Z","caller":"traceutil/trace.go:171","msg":"trace[854533650] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"561.116543ms","start":"2026-03-11T01:46:15.765319Z","end":"2026-03-11T01:46:16.326436Z","steps":["trace[854533650] 'process raft request' (duration: 194.166108ms)","trace[854533650] 'compare' (duration: 366.530265ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:16.32676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:15.765296Z","time spent":"561.40963ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:16.369144Z","caller":"traceutil/trace.go:171","msg":"trace[1325600506] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"603.289355ms","start":"2026-03-11T01:46:15.765726Z","end":"2026-03-11T01:46:16.369015Z","steps":["trace[1325600506] 'process raft request' (duration: 603.032789ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:16.369261Z","caller":"traceutil/trace.go:171","msg":"trace[603514913] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"603.142481ms","start":"2026-03-11T01:46:15.766052Z","end":"2026-03-11T01:46:16.369195Z","steps":["trace[603514913] 'process raft request' (duration: 602.866905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:16.369308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:15.765708Z","time spent":"603.5111ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:16.369543Z","caller":"traceutil/trace.go:171","msg":"trace[617750441] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"603.296335ms","start":"2026-03-11T01:46:15.766188Z","end":"2026-03-11T01:46:16.369485Z","steps":["trace[617750441] 'process raft request' (duration: 602.776373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:16.369817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:15.766176Z","time spent":"603.494849ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:16.370812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:15.766038Z","time spent":"603.305946ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:16.709604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.816842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-11T01:46:16.709581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.894898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:16.709725Z","caller":"traceutil/trace.go:171","msg":"trace[1792336160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"110.110114ms","start":"2026-03-11T01:46:16.599587Z","end":"2026-03-11T01:46:16.709697Z","steps":["trace[1792336160] 'range keys from in-memory index tree' (duration: 109.819616ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:16.709698Z","caller":"traceutil/trace.go:171","msg":"trace[2098845894] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1007; }","duration":"190.978026ms","start":"2026-03-11T01:46:16.518701Z","end":"2026-03-11T01:46:16.709679Z","steps":["trace[2098845894] 'count revisions from in-memory index tree' (duration: 190.670008ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:18.174698Z","caller":"traceutil/trace.go:171","msg":"trace[1615117860] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"405.237394ms","start":"2026-03-11T01:46:17.769436Z","end":"2026-03-11T01:46:18.174674Z","steps":["trace[1615117860] 'process raft request' (duration: 388.580143ms)","trace[1615117860] 'compare' (duration: 16.208531ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:18.17483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:17.769396Z","time spent":"405.369847ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:46:18.174871Z","caller":"traceutil/trace.go:171","msg":"trace[1266829950] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"333.660812ms","start":"2026-03-11T01:46:17.841198Z","end":"2026-03-11T01:46:18.174858Z","steps":["trace[1266829950] 'process raft request' (duration: 333.413335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:18.174971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:17.841177Z","time spent":"333.746633ms","remote":"127.0.0.1:44048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":855,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-11T01:46:18.402556Z","caller":"traceutil/trace.go:171","msg":"trace[2019966483] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"162.254608ms","start":"2026-03-11T01:46:18.240261Z","end":"2026-03-11T01:46:18.402515Z","steps":["trace[2019966483] 'process raft request' (duration: 72.24358ms)","trace[2019966483] 'compare' (duration: 89.738802ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:18.402717Z","caller":"traceutil/trace.go:171","msg":"trace[844533354] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"159.372001ms","start":"2026-03-11T01:46:18.243328Z","end":"2026-03-11T01:46:18.4027Z","steps":["trace[844533354] 'process raft request' (duration: 159.112285ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:20.056292Z","caller":"traceutil/trace.go:171","msg":"trace[297866159] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"109.772234ms","start":"2026-03-11T01:46:19.94649Z","end":"2026-03-11T01:46:20.056262Z","steps":["trace[297866159] 'process raft request' (duration: 109.531499ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:22.74098Z","caller":"traceutil/trace.go:171","msg":"trace[774502939] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"131.383201ms","start":"2026-03-11T01:46:22.60957Z","end":"2026-03-11T01:46:22.740953Z","steps":["trace[774502939] 'process raft request' (duration: 131.196697ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:35.221322Z","caller":"traceutil/trace.go:171","msg":"trace[1118834925] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"135.180782ms","start":"2026-03-11T01:46:35.086117Z","end":"2026-03-11T01:46:35.221297Z","steps":["trace[1118834925] 'process raft request' (duration: 135.049529ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:48.072578Z","caller":"traceutil/trace.go:171","msg":"trace[880364250] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"116.979244ms","start":"2026-03-11T01:46:47.955578Z","end":"2026-03-11T01:46:48.072558Z","steps":["trace[880364250] 'process raft request' (duration: 116.862451ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:54.835373Z","caller":"traceutil/trace.go:171","msg":"trace[141049511] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"109.255741ms","start":"2026-03-11T01:46:54.726089Z","end":"2026-03-11T01:46:54.835344Z","steps":["trace[141049511] 'process raft request' (duration: 17.630024ms)","trace[141049511] 'compare' (duration: 91.479754ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:54.835676Z","caller":"traceutil/trace.go:171","msg":"trace[1335721287] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"109.542288ms","start":"2026-03-11T01:46:54.726102Z","end":"2026-03-11T01:46:54.835644Z","steps":["trace[1335721287] 'process raft request' (duration: 109.19505ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:54.835714Z","caller":"traceutil/trace.go:171","msg":"trace[1248609233] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"108.678998ms","start":"2026-03-11T01:46:54.727012Z","end":"2026-03-11T01:46:54.835691Z","steps":["trace[1248609233] 'process raft request' (duration: 108.639687ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:54.836058Z","caller":"traceutil/trace.go:171","msg":"trace[1221060675] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"109.63793ms","start":"2026-03-11T01:46:54.726406Z","end":"2026-03-11T01:46:54.836044Z","steps":["trace[1221060675] 'process raft request' (duration: 109.082117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:55.053712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.638942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:46:55.053824Z","caller":"traceutil/trace.go:171","msg":"trace[1341798673] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1182; }","duration":"115.795675ms","start":"2026-03-11T01:46:54.937996Z","end":"2026-03-11T01:46:55.053792Z","steps":["trace[1341798673] 'count revisions from in-memory index tree' (duration: 115.571251ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:23.167696Z","caller":"traceutil/trace.go:171","msg":"trace[1223955480] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"108.464973ms","start":"2026-03-11T01:47:23.059205Z","end":"2026-03-11T01:47:23.16767Z","steps":["trace[1223955480] 'process raft request' (duration: 104.756756ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:23.167906Z","caller":"traceutil/trace.go:171","msg":"trace[102736196] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"108.34996ms","start":"2026-03-11T01:47:23.059491Z","end":"2026-03-11T01:47:23.167841Z","steps":["trace[102736196] 'process raft request' (duration: 108.31225ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:23.167941Z","caller":"traceutil/trace.go:171","msg":"trace[1676218383] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"108.630037ms","start":"2026-03-11T01:47:23.059275Z","end":"2026-03-11T01:47:23.167905Z","steps":["trace[1676218383] 'process raft request' (duration: 108.35142ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:25.334148Z","caller":"traceutil/trace.go:171","msg":"trace[905608124] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"158.05702ms","start":"2026-03-11T01:47:25.176068Z","end":"2026-03-11T01:47:25.334125Z","steps":["trace[905608124] 'process raft request' (duration: 158.010019ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:25.334193Z","caller":"traceutil/trace.go:171","msg":"trace[1632695528] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"158.721035ms","start":"2026-03-11T01:47:25.175445Z","end":"2026-03-11T01:47:25.334166Z","steps":["trace[1632695528] 'process raft request' (duration: 158.209783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:47:37.330868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.035626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-11T01:47:37.330892Z","caller":"traceutil/trace.go:171","msg":"trace[559673239] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"181.799672ms","start":"2026-03-11T01:47:37.149066Z","end":"2026-03-11T01:47:37.330866Z","steps":["trace[559673239] 'process raft request' (duration: 180.43194ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:37.33095Z","caller":"traceutil/trace.go:171","msg":"trace[474494712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"100.155629ms","start":"2026-03-11T01:47:37.23078Z","end":"2026-03-11T01:47:37.330936Z","steps":["trace[474494712] 'agreement among raft nodes before linearized reading' (duration: 99.954374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:47:37.583813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.598409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:47:37.58418Z","caller":"traceutil/trace.go:171","msg":"trace[1362713548] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"187.360539ms","start":"2026-03-11T01:47:37.396795Z","end":"2026-03-11T01:47:37.584155Z","steps":["trace[1362713548] 'process raft request' (duration: 187.272797ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:37.584329Z","caller":"traceutil/trace.go:171","msg":"trace[1075202179] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"187.999714ms","start":"2026-03-11T01:47:37.396285Z","end":"2026-03-11T01:47:37.584285Z","steps":["trace[1075202179] 'process raft request' (duration: 86.828803ms)","trace[1075202179] 'compare' (duration: 100.438875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:47:55.793831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.161049ms","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-03-11T01:47:55.793914Z","caller":"traceutil/trace.go:171","msg":"trace[1213825423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1427; }","duration":"131.329804ms","start":"2026-03-11T01:47:55.662566Z","end":"2026-03-11T01:47:55.793896Z","steps":["trace[1213825423] 'range keys from in-memory index tree' (duration: 130.974115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:47:55.794151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.764581ms","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-03-11T01:47:55.794186Z","caller":"traceutil/trace.go:171","msg":"trace[690925343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1427; }","duration":"130.801491ms","start":"2026-03-11T01:47:55.663376Z","end":"2026-03-11T01:47:55.794177Z","steps":["trace[690925343] 'range keys from in-memory index tree' (duration: 130.554616ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:47:56.853124Z","caller":"traceutil/trace.go:171","msg":"trace[1868687410] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"140.720919ms","start":"2026-03-11T01:47:56.712353Z","end":"2026-03-11T01:47:56.853074Z","steps":["trace[1868687410] 'process raft request' (duration: 140.365841ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:48:11.568465Z","caller":"traceutil/trace.go:171","msg":"trace[1293718334] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"211.862211ms","start":"2026-03-11T01:48:11.356582Z","end":"2026-03-11T01:48:11.568445Z","steps":["trace[1293718334] 'process raft request' (duration: 211.739618ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:48:11.804938Z","caller":"traceutil/trace.go:171","msg":"trace[411090684] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"189.485529ms","start":"2026-03-11T01:48:11.615432Z","end":"2026-03-11T01:48:11.804917Z","steps":["trace[411090684] 'read index received' (duration: 138.125109ms)","trace[411090684] 'applied index is now lower than readState.Index' (duration: 51.359449ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:48:11.805146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.693883ms","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-03-11T01:48:11.805185Z","caller":"traceutil/trace.go:171","msg":"trace[1747743499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1490; }","duration":"189.767445ms","start":"2026-03-11T01:48:11.615406Z","end":"2026-03-11T01:48:11.805174Z","steps":["trace[1747743499] 'agreement among raft nodes before linearized reading' (duration: 189.590181ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:48:11.805321Z","caller":"traceutil/trace.go:171","msg":"trace[1414639821] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"226.948318ms","start":"2026-03-11T01:48:11.57835Z","end":"2026-03-11T01:48:11.805299Z","steps":["trace[1414639821] 'process raft request' (duration: 175.263591ms)","trace[1414639821] 'compare' (duration: 51.045953ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:49:24.178578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.770421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-11T01:49:24.178637Z","caller":"traceutil/trace.go:171","msg":"trace[308070787] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1812; }","duration":"128.870874ms","start":"2026-03-11T01:49:24.049754Z","end":"2026-03-11T01:49:24.178625Z","steps":["trace[308070787] 'range keys from in-memory index tree' (duration: 128.640838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:49:24.178865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.787029ms","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:508"} {"level":"info","ts":"2026-03-11T01:49:24.178945Z","caller":"traceutil/trace.go:171","msg":"trace[655422829] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1812; }","duration":"115.896842ms","start":"2026-03-11T01:49:24.063024Z","end":"2026-03-11T01:49:24.17892Z","steps":["trace[655422829] 'range keys from in-memory index tree' (duration: 115.631276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:49:24.178605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.978509ms","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-03-11T01:49:24.179027Z","caller":"traceutil/trace.go:171","msg":"trace[695804840] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1812; }","duration":"173.431439ms","start":"2026-03-11T01:49:24.005555Z","end":"2026-03-11T01:49:24.178986Z","steps":["trace[695804840] 'count revisions from in-memory index tree' (duration: 172.881247ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:24.349934Z","caller":"traceutil/trace.go:171","msg":"trace[2141035513] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"166.910527ms","start":"2026-03-11T01:49:24.182999Z","end":"2026-03-11T01:49:24.349909Z","steps":["trace[2141035513] 'process raft request' (duration: 166.781554ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:24.350914Z","caller":"traceutil/trace.go:171","msg":"trace[1202739532] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"167.727806ms","start":"2026-03-11T01:49:24.183145Z","end":"2026-03-11T01:49:24.350873Z","steps":["trace[1202739532] 'process raft request' (duration: 167.409659ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:24.351005Z","caller":"traceutil/trace.go:171","msg":"trace[1541700104] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"167.099781ms","start":"2026-03-11T01:49:24.183891Z","end":"2026-03-11T01:49:24.35099Z","steps":["trace[1541700104] 'process raft request' (duration: 166.734222ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:24.351113Z","caller":"traceutil/trace.go:171","msg":"trace[636220176] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"166.881216ms","start":"2026-03-11T01:49:24.184218Z","end":"2026-03-11T01:49:24.351099Z","steps":["trace[636220176] 'process raft request' (duration: 166.459897ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:25.619513Z","caller":"traceutil/trace.go:171","msg":"trace[573899679] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"226.612515ms","start":"2026-03-11T01:49:25.392873Z","end":"2026-03-11T01:49:25.619485Z","steps":["trace[573899679] 'process raft request' (duration: 226.428701ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:57.855863Z","caller":"traceutil/trace.go:171","msg":"trace[989486111] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"110.098217ms","start":"2026-03-11T01:49:57.745732Z","end":"2026-03-11T01:49:57.85583Z","steps":["trace[989486111] 'process raft request' (duration: 109.81698ms)"],"step_count":1}