{"level":"warn","ts":"2026-04-23T17:04:03.030264Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T17:04:03.030406Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.76:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.76:2380","--initial-cluster=instance=https://199.19.213.76:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.76:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.76:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T17:04:03.030482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T17:04:03.03051Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.76:2380"]} {"level":"info","ts":"2026-04-23T17:04:03.030542Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T17:04:03.031283Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.76:2379"]} {"level":"info","ts":"2026-04-23T17:04:03.03138Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.76:2380"],"listen-peer-urls":["https://199.19.213.76:2380"],"advertise-client-urls":["https://199.19.213.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.76:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T17:04:03.039419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.821657ms"} {"level":"info","ts":"2026-04-23T17:04:03.049399Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f66825837990484a","cluster-id":"3de51efb4d16c986"} {"level":"info","ts":"2026-04-23T17:04:03.049575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T17:04:03.049651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became follower at term 0"} {"level":"info","ts":"2026-04-23T17:04:03.049783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f66825837990484a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T17:04:03.04983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became follower at term 1"} {"level":"info","ts":"2026-04-23T17:04:03.049937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a switched to configuration voters=(17755482777518622794)"} {"level":"warn","ts":"2026-04-23T17:04:03.065165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T17:04:03.068442Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T17:04:03.073085Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T17:04:03.076319Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f66825837990484a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T17:04:03.076679Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f66825837990484a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T17:04:03.076952Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T17:04:03.077091Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T17:04:03.077225Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T17:04:03.079419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a switched to configuration voters=(17755482777518622794)"} {"level":"info","ts":"2026-04-23T17:04:03.080044Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3de51efb4d16c986","local-member-id":"f66825837990484a","added-peer-id":"f66825837990484a","added-peer-peer-urls":["https://199.19.213.76:2380"]} {"level":"info","ts":"2026-04-23T17:04:03.081546Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T17:04:03.081622Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.76:2380"} {"level":"info","ts":"2026-04-23T17:04:03.081661Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.76:2380"} {"level":"info","ts":"2026-04-23T17:04:03.081887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f66825837990484a","initial-advertise-peer-urls":["https://199.19.213.76:2380"],"listen-peer-urls":["https://199.19.213.76:2380"],"advertise-client-urls":["https://199.19.213.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T17:04:03.081957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T17:04:03.451369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T17:04:03.451428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T17:04:03.451467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a received MsgPreVoteResp from f66825837990484a at term 1"} {"level":"info","ts":"2026-04-23T17:04:03.451488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became candidate at term 2"} {"level":"info","ts":"2026-04-23T17:04:03.451499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a received MsgVoteResp from f66825837990484a at term 2"} {"level":"info","ts":"2026-04-23T17:04:03.451667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became leader at term 2"} {"level":"info","ts":"2026-04-23T17:04:03.451681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f66825837990484a elected leader f66825837990484a at term 2"} {"level":"info","ts":"2026-04-23T17:04:03.456606Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T17:04:03.456733Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f66825837990484a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.76:2379]}","request-path":"/0/members/f66825837990484a/attributes","cluster-id":"3de51efb4d16c986","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T17:04:03.456848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T17:04:03.456905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T17:04:03.457217Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T17:04:03.457275Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T17:04:03.460247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.76:2379"} {"level":"info","ts":"2026-04-23T17:04:03.460405Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3de51efb4d16c986","local-member-id":"f66825837990484a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T17:04:03.460479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T17:04:03.460589Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T17:04:03.460622Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-23T17:04:29.130381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209149346774511102,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T17:04:29.370128Z","caller":"traceutil/trace.go:171","msg":"trace[504147120] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"772.076111ms","start":"2026-04-23T17:04:28.598021Z","end":"2026-04-23T17:04:29.370097Z","steps":["trace[504147120] 'process raft request' (duration: 771.877171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.370904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.598001Z","time spent":"772.237729ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:04:29.376838Z","caller":"traceutil/trace.go:171","msg":"trace[201156249] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"684.370661ms","start":"2026-04-23T17:04:28.692437Z","end":"2026-04-23T17:04:29.376808Z","steps":["trace[201156249] 'process raft request' (duration: 684.232647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.376956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.692395Z","time spent":"684.506335ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.376942Z","caller":"traceutil/trace.go:171","msg":"trace[419301306] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"677.085021ms","start":"2026-04-23T17:04:28.699829Z","end":"2026-04-23T17:04:29.376914Z","steps":["trace[419301306] 'process raft request' (duration: 676.903853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.37704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.699809Z","time spent":"677.183201ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.377029Z","caller":"traceutil/trace.go:171","msg":"trace[239056903] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"645.624979ms","start":"2026-04-23T17:04:28.731378Z","end":"2026-04-23T17:04:29.377003Z","steps":["trace[239056903] 'process raft request' (duration: 645.494446ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.377062Z","caller":"traceutil/trace.go:171","msg":"trace[214609625] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"641.616862ms","start":"2026-04-23T17:04:28.735436Z","end":"2026-04-23T17:04:29.377053Z","steps":["trace[214609625] 'process raft request' (duration: 641.466597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.735427Z","time spent":"641.679528ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.377114Z","caller":"traceutil/trace.go:171","msg":"trace[16655613] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"666.372894ms","start":"2026-04-23T17:04:28.710727Z","end":"2026-04-23T17:04:29.3771Z","steps":["trace[16655613] 'process raft request' (duration: 666.061472ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.377009Z","caller":"traceutil/trace.go:171","msg":"trace[1248591534] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"665.965503ms","start":"2026-04-23T17:04:28.710987Z","end":"2026-04-23T17:04:29.376952Z","steps":["trace[1248591534] 'process raft request' (duration: 665.823149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.71098Z","time spent":"666.200677ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.377213Z","caller":"traceutil/trace.go:171","msg":"trace[1892787319] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:472; }","duration":"747.112788ms","start":"2026-04-23T17:04:28.630088Z","end":"2026-04-23T17:04:29.377201Z","steps":["trace[1892787319] 'read index received' (duration: 739.856682ms)","trace[1892787319] 'applied index is now lower than readState.Index' (duration: 7.255416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:04:29.377211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.710718Z","time spent":"666.445071ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.377219Z","caller":"traceutil/trace.go:171","msg":"trace[124409258] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"655.576538ms","start":"2026-04-23T17:04:28.721629Z","end":"2026-04-23T17:04:29.377206Z","steps":["trace[124409258] 'process raft request' (duration: 655.205421ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.377244Z","caller":"traceutil/trace.go:171","msg":"trace[1784351973] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"636.295693ms","start":"2026-04-23T17:04:28.740942Z","end":"2026-04-23T17:04:29.377238Z","steps":["trace[1784351973] 'process raft request' (duration: 636.018924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.740923Z","time spent":"636.335677ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.37728Z","caller":"traceutil/trace.go:171","msg":"trace[736497797] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"612.352143ms","start":"2026-04-23T17:04:28.76492Z","end":"2026-04-23T17:04:29.377272Z","steps":["trace[736497797] 'process raft request' (duration: 612.145651ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.377276Z","caller":"traceutil/trace.go:171","msg":"trace[1356728629] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"639.205818ms","start":"2026-04-23T17:04:28.738056Z","end":"2026-04-23T17:04:29.377262Z","steps":["trace[1356728629] 'process raft request' (duration: 638.877954ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.377327Z","caller":"traceutil/trace.go:171","msg":"trace[341481719] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"486.834925ms","start":"2026-04-23T17:04:28.890486Z","end":"2026-04-23T17:04:29.377321Z","steps":["trace[341481719] 'process raft request' (duration: 486.631804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.764899Z","time spent":"612.399528ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:04:29.377352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.890463Z","time spent":"486.8782ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:04:29.377358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.738043Z","time spent":"639.276535ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:04:29.377202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.731367Z","time spent":"645.747701ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:04:29.377395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.305698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T17:04:29.377432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.303351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-23T17:04:29.377455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.517644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-23T17:04:29.377287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.72162Z","time spent":"655.638616ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:29.377463Z","caller":"traceutil/trace.go:171","msg":"trace[2044136710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"263.366926ms","start":"2026-04-23T17:04:29.114087Z","end":"2026-04-23T17:04:29.377454Z","steps":["trace[2044136710] 'agreement among raft nodes before linearized reading' (duration: 263.308131ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.37746Z","caller":"traceutil/trace.go:171","msg":"trace[1005387288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"747.405818ms","start":"2026-04-23T17:04:28.630044Z","end":"2026-04-23T17:04:29.37745Z","steps":["trace[1005387288] 'agreement among raft nodes before linearized reading' (duration: 747.269174ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:29.377475Z","caller":"traceutil/trace.go:171","msg":"trace[1609390490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"264.58017ms","start":"2026-04-23T17:04:29.112889Z","end":"2026-04-23T17:04:29.377469Z","steps":["trace[1609390490] 'agreement among raft nodes before linearized reading' (duration: 264.535986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.932331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T17:04:29.377495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.630026Z","time spent":"747.462244ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:04:29.377523Z","caller":"traceutil/trace.go:171","msg":"trace[1222300994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"646.006959ms","start":"2026-04-23T17:04:28.7315Z","end":"2026-04-23T17:04:29.377507Z","steps":["trace[1222300994] 'agreement among raft nodes before linearized reading' (duration: 645.886347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.731494Z","time spent":"646.061684ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T17:04:29.377758Z","caller":"traceutil/trace.go:171","msg":"trace[1671629043] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"668.797481ms","start":"2026-04-23T17:04:28.708942Z","end":"2026-04-23T17:04:29.377739Z","steps":["trace[1671629043] 'process raft request' (duration: 667.822042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:29.377822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:28.708933Z","time spent":"668.856587ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:04:32.628195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.361033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:04:32.62825Z","caller":"traceutil/trace.go:171","msg":"trace[1813785589] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:543; }","duration":"153.456843ms","start":"2026-04-23T17:04:32.474781Z","end":"2026-04-23T17:04:32.628237Z","steps":["trace[1813785589] 'range keys from in-memory index tree' (duration: 153.306737ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:33.393525Z","caller":"traceutil/trace.go:171","msg":"trace[564652914] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"384.558226ms","start":"2026-04-23T17:04:33.008941Z","end":"2026-04-23T17:04:33.393499Z","steps":["trace[564652914] 'process raft request' (duration: 383.994118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:33.393807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.008905Z","time spent":"384.6952ms","remote":"127.0.0.1:44054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:04:34.088975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.664459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:04:34.089119Z","caller":"traceutil/trace.go:171","msg":"trace[1498906524] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"675.216883ms","start":"2026-04-23T17:04:33.413862Z","end":"2026-04-23T17:04:34.089078Z","steps":["trace[1498906524] 'process raft request' (duration: 38.223379ms)","trace[1498906524] 'compare' (duration: 636.509824ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:04:34.089187Z","caller":"traceutil/trace.go:171","msg":"trace[1293553666] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"640.604521ms","start":"2026-04-23T17:04:33.44857Z","end":"2026-04-23T17:04:34.089174Z","steps":["trace[1293553666] 'process raft request' (duration: 640.536184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:34.089236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.413837Z","time spent":"675.343156ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:04:34.089244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.448549Z","time spent":"640.668437ms","remote":"127.0.0.1:44160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:04:34.089239Z","caller":"traceutil/trace.go:171","msg":"trace[2026781880] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"673.80511ms","start":"2026-04-23T17:04:33.4154Z","end":"2026-04-23T17:04:34.089205Z","steps":["trace[2026781880] 'read index received' (duration: 36.685083ms)","trace[2026781880] 'applied index is now lower than readState.Index' (duration: 637.117296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:04:34.089449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.032743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T17:04:34.089496Z","caller":"traceutil/trace.go:171","msg":"trace[1044550050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"674.172787ms","start":"2026-04-23T17:04:33.415312Z","end":"2026-04-23T17:04:34.089485Z","steps":["trace[1044550050] 'agreement among raft nodes before linearized reading' (duration: 674.053945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:34.089529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.415296Z","time spent":"674.225833ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:04:34.089558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.987935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:04:34.089595Z","caller":"traceutil/trace.go:171","msg":"trace[1414969868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"348.059913ms","start":"2026-04-23T17:04:33.741523Z","end":"2026-04-23T17:04:34.089583Z","steps":["trace[1414969868] 'agreement among raft nodes before linearized reading' (duration: 347.998227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:34.089627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.741503Z","time spent":"348.116958ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:04:34.089627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.428139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-23T17:04:34.089671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.363245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T17:04:34.089702Z","caller":"traceutil/trace.go:171","msg":"trace[821574670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"653.41408ms","start":"2026-04-23T17:04:33.436279Z","end":"2026-04-23T17:04:34.089693Z","steps":["trace[821574670] 'agreement among raft nodes before linearized reading' (duration: 653.361224ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:34.089709Z","caller":"traceutil/trace.go:171","msg":"trace[1814510688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"658.555041ms","start":"2026-04-23T17:04:33.431131Z","end":"2026-04-23T17:04:34.089686Z","steps":["trace[1814510688] 'agreement among raft nodes before linearized reading' (duration: 658.386014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:34.089726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.436265Z","time spent":"653.455165ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:04:34.089754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:33.431116Z","time spent":"658.629689ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:04:35.48916Z","caller":"traceutil/trace.go:171","msg":"trace[894793534] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"503.897695ms","start":"2026-04-23T17:04:34.985244Z","end":"2026-04-23T17:04:35.489141Z","steps":["trace[894793534] 'process raft request' (duration: 503.55116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:35.489268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:34.98521Z","time spent":"504.002237ms","remote":"127.0.0.1:44054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:04:36.721226Z","caller":"traceutil/trace.go:171","msg":"trace[1615345566] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"277.781309ms","start":"2026-04-23T17:04:36.44342Z","end":"2026-04-23T17:04:36.721202Z","steps":["trace[1615345566] 'process raft request' (duration: 277.065747ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:36.722587Z","caller":"traceutil/trace.go:171","msg":"trace[1142059881] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"117.07793ms","start":"2026-04-23T17:04:36.605442Z","end":"2026-04-23T17:04:36.72252Z","steps":["trace[1142059881] 'process raft request' (duration: 116.895492ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:38.019559Z","caller":"traceutil/trace.go:171","msg":"trace[885732390] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"277.909763ms","start":"2026-04-23T17:04:37.741594Z","end":"2026-04-23T17:04:38.019504Z","steps":["trace[885732390] 'read index received' (duration: 277.672059ms)","trace[885732390] 'applied index is now lower than readState.Index' (duration: 236.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:04:38.019679Z","caller":"traceutil/trace.go:171","msg":"trace[1231696124] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"283.975228ms","start":"2026-04-23T17:04:37.735657Z","end":"2026-04-23T17:04:38.019632Z","steps":["trace[1231696124] 'process raft request' (duration: 283.719712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:38.01984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.218185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:04:38.01994Z","caller":"traceutil/trace.go:171","msg":"trace[1997891356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"278.380771ms","start":"2026-04-23T17:04:37.741534Z","end":"2026-04-23T17:04:38.019914Z","steps":["trace[1997891356] 'agreement among raft nodes before linearized reading' (duration: 278.150878ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:41.338888Z","caller":"traceutil/trace.go:171","msg":"trace[2110484199] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"134.070694ms","start":"2026-04-23T17:04:41.204786Z","end":"2026-04-23T17:04:41.338857Z","steps":["trace[2110484199] 'process raft request' (duration: 133.86314ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:50.398322Z","caller":"traceutil/trace.go:171","msg":"trace[1103063357] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"188.465261ms","start":"2026-04-23T17:04:50.209834Z","end":"2026-04-23T17:04:50.398299Z","steps":["trace[1103063357] 'process raft request' (duration: 187.722108ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:50.402191Z","caller":"traceutil/trace.go:171","msg":"trace[257718530] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"190.58756ms","start":"2026-04-23T17:04:50.211588Z","end":"2026-04-23T17:04:50.402176Z","steps":["trace[257718530] 'process raft request' (duration: 190.549669ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:04:50.402476Z","caller":"traceutil/trace.go:171","msg":"trace[1839773899] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"192.135018ms","start":"2026-04-23T17:04:50.210331Z","end":"2026-04-23T17:04:50.402466Z","steps":["trace[1839773899] 'process raft request' (duration: 191.734841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:53.238518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.803712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:04:53.238652Z","caller":"traceutil/trace.go:171","msg":"trace[1544525276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"496.971845ms","start":"2026-04-23T17:04:52.741648Z","end":"2026-04-23T17:04:53.23862Z","steps":["trace[1544525276] 'range keys from in-memory index tree' (duration: 496.7268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:04:53.238693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:04:52.741628Z","time spent":"497.055816ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T17:04:53.489085Z","caller":"traceutil/trace.go:171","msg":"trace[1854230535] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"230.858045ms","start":"2026-04-23T17:04:53.258211Z","end":"2026-04-23T17:04:53.489069Z","steps":["trace[1854230535] 'process raft request' (duration: 230.737353ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:05:04.681448Z","caller":"traceutil/trace.go:171","msg":"trace[412829075] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"213.515894ms","start":"2026-04-23T17:05:04.467905Z","end":"2026-04-23T17:05:04.681421Z","steps":["trace[412829075] 'read index received' (duration: 204.543482ms)","trace[412829075] 'applied index is now lower than readState.Index' (duration: 8.971192ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:05:04.681589Z","caller":"traceutil/trace.go:171","msg":"trace[614799602] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"225.788104ms","start":"2026-04-23T17:05:04.455757Z","end":"2026-04-23T17:05:04.681545Z","steps":["trace[614799602] 'process raft request' (duration: 216.70889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:05:04.681728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.801918ms","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-04-23T17:05:04.681734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.303635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T17:05:04.681838Z","caller":"traceutil/trace.go:171","msg":"trace[460377827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:857; }","duration":"210.427867ms","start":"2026-04-23T17:05:04.471387Z","end":"2026-04-23T17:05:04.681815Z","steps":["trace[460377827] 'agreement among raft nodes before linearized reading' (duration: 210.200224ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:05:04.681772Z","caller":"traceutil/trace.go:171","msg":"trace[2073858258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:857; }","duration":"213.898081ms","start":"2026-04-23T17:05:04.467863Z","end":"2026-04-23T17:05:04.681761Z","steps":["trace[2073858258] 'agreement among raft nodes before linearized reading' (duration: 213.703367ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:06:33.281314Z","caller":"traceutil/trace.go:171","msg":"trace[453503755] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"111.785192ms","start":"2026-04-23T17:06:33.169505Z","end":"2026-04-23T17:06:33.281291Z","steps":["trace[453503755] 'process raft request' (duration: 111.742811ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:06:33.281446Z","caller":"traceutil/trace.go:171","msg":"trace[1584321942] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"111.946996ms","start":"2026-04-23T17:06:33.169456Z","end":"2026-04-23T17:06:33.281403Z","steps":["trace[1584321942] 'process raft request' (duration: 86.556963ms)","trace[1584321942] 'compare' (duration: 25.123438ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:06:34.868089Z","caller":"traceutil/trace.go:171","msg":"trace[534767339] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"117.031971ms","start":"2026-04-23T17:06:34.751031Z","end":"2026-04-23T17:06:34.868063Z","steps":["trace[534767339] 'process raft request' (duration: 116.846458ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:06:59.022722Z","caller":"traceutil/trace.go:171","msg":"trace[1104295436] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"139.490916ms","start":"2026-04-23T17:06:58.883205Z","end":"2026-04-23T17:06:59.022696Z","steps":["trace[1104295436] 'process raft request' (duration: 139.346283ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:06:59.08059Z","caller":"traceutil/trace.go:171","msg":"trace[609408573] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"132.010824ms","start":"2026-04-23T17:06:58.948547Z","end":"2026-04-23T17:06:59.080558Z","steps":["trace[609408573] 'process raft request' (duration: 101.535303ms)","trace[609408573] 'compare' (duration: 30.330058ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:06:59.641726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.630544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T17:06:59.641852Z","caller":"traceutil/trace.go:171","msg":"trace[1210613414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"111.771767ms","start":"2026-04-23T17:06:59.530023Z","end":"2026-04-23T17:06:59.641795Z","steps":["trace[1210613414] 'range keys from in-memory index tree' (duration: 111.39056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:06:59.641913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.012632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T17:06:59.642089Z","caller":"traceutil/trace.go:171","msg":"trace[2140400942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"196.218796ms","start":"2026-04-23T17:06:59.445849Z","end":"2026-04-23T17:06:59.642068Z","steps":["trace[2140400942] 'range keys from in-memory index tree' (duration: 195.781356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:06:59.860915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.191659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:06:59.861774Z","caller":"traceutil/trace.go:171","msg":"trace[162124887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"119.430074ms","start":"2026-04-23T17:06:59.741625Z","end":"2026-04-23T17:06:59.861055Z","steps":["trace[162124887] 'range keys from in-memory index tree' (duration: 119.091198ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:01.186924Z","caller":"traceutil/trace.go:171","msg":"trace[458758040] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"102.240556ms","start":"2026-04-23T17:07:01.084663Z","end":"2026-04-23T17:07:01.186904Z","steps":["trace[458758040] 'process raft request' (duration: 102.150494ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:01.869676Z","caller":"traceutil/trace.go:171","msg":"trace[145394403] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"451.051478ms","start":"2026-04-23T17:07:01.418603Z","end":"2026-04-23T17:07:01.869655Z","steps":["trace[145394403] 'process raft request' (duration: 450.937626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:01.869824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:01.418579Z","time spent":"451.172731ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:01.869843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.476689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T17:07:01.86988Z","caller":"traceutil/trace.go:171","msg":"trace[858932446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"177.557741ms","start":"2026-04-23T17:07:01.692314Z","end":"2026-04-23T17:07:01.869871Z","steps":["trace[858932446] 'agreement among raft nodes before linearized reading' (duration: 177.418158ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:01.86968Z","caller":"traceutil/trace.go:171","msg":"trace[1142426030] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1308; }","duration":"177.288606ms","start":"2026-04-23T17:07:01.692366Z","end":"2026-04-23T17:07:01.869654Z","steps":["trace[1142426030] 'read index received' (duration: 177.281516ms)","trace[1142426030] 'applied index is now lower than readState.Index' (duration: 5.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:02.144729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.030549ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T17:07:02.14498Z","caller":"traceutil/trace.go:171","msg":"trace[1701114556] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"270.018701ms","start":"2026-04-23T17:07:01.874942Z","end":"2026-04-23T17:07:02.144961Z","steps":["trace[1701114556] 'process raft request' (duration: 269.889939ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:02.144985Z","caller":"traceutil/trace.go:171","msg":"trace[498918961] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"274.881513ms","start":"2026-04-23T17:07:01.870083Z","end":"2026-04-23T17:07:02.144964Z","steps":["trace[498918961] 'read index received' (duration: 75.63935ms)","trace[498918961] 'applied index is now lower than readState.Index' (duration: 199.240433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:02.14525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.913997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T17:07:02.145293Z","caller":"traceutil/trace.go:171","msg":"trace[1893174360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"448.987189ms","start":"2026-04-23T17:07:01.696295Z","end":"2026-04-23T17:07:02.145283Z","steps":["trace[1893174360] 'agreement among raft nodes before linearized reading' (duration: 448.758824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:02.145289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.279709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:02.145458Z","caller":"traceutil/trace.go:171","msg":"trace[704872563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"403.493054ms","start":"2026-04-23T17:07:01.741946Z","end":"2026-04-23T17:07:02.145439Z","steps":["trace[704872563] 'agreement among raft nodes before linearized reading' (duration: 403.272359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:02.145562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:01.741929Z","time spent":"403.597905ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:07:02.145325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:01.696271Z","time spent":"449.046179ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:07:02.358505Z","caller":"traceutil/trace.go:171","msg":"trace[1333527063] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"209.430028ms","start":"2026-04-23T17:07:02.149036Z","end":"2026-04-23T17:07:02.358466Z","steps":["trace[1333527063] 'process raft request' (duration: 157.892886ms)","trace[1333527063] 'compare' (duration: 51.140283ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:02.554276Z","caller":"traceutil/trace.go:171","msg":"trace[2128342593] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"182.965354ms","start":"2026-04-23T17:07:02.371265Z","end":"2026-04-23T17:07:02.55423Z","steps":["trace[2128342593] 'process raft request' (duration: 89.522085ms)","trace[2128342593] 'compare' (duration: 93.313137ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:02.786311Z","caller":"traceutil/trace.go:171","msg":"trace[711650408] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"205.955811ms","start":"2026-04-23T17:07:02.580195Z","end":"2026-04-23T17:07:02.78615Z","steps":["trace[711650408] 'process raft request' (duration: 143.750287ms)","trace[711650408] 'compare' (duration: 62.023321ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:03.224757Z","caller":"traceutil/trace.go:171","msg":"trace[1399713932] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"117.706161ms","start":"2026-04-23T17:07:03.10703Z","end":"2026-04-23T17:07:03.224737Z","steps":["trace[1399713932] 'process raft request' (duration: 117.566678ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:03.291412Z","caller":"traceutil/trace.go:171","msg":"trace[1199480955] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"100.138046ms","start":"2026-04-23T17:07:03.19126Z","end":"2026-04-23T17:07:03.291398Z","steps":["trace[1199480955] 'process raft request' (duration: 100.047645ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:04.923387Z","caller":"traceutil/trace.go:171","msg":"trace[1037727642] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"129.541497ms","start":"2026-04-23T17:07:04.793822Z","end":"2026-04-23T17:07:04.923364Z","steps":["trace[1037727642] 'process raft request' (duration: 129.407525ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:06.859727Z","caller":"traceutil/trace.go:171","msg":"trace[52998203] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"344.071881ms","start":"2026-04-23T17:07:06.51563Z","end":"2026-04-23T17:07:06.859702Z","steps":["trace[52998203] 'read index received' (duration: 343.790785ms)","trace[52998203] 'applied index is now lower than readState.Index' (duration: 279.836µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:06.859864Z","caller":"traceutil/trace.go:171","msg":"trace[141007551] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"369.428134ms","start":"2026-04-23T17:07:06.49042Z","end":"2026-04-23T17:07:06.859848Z","steps":["trace[141007551] 'process raft request' (duration: 369.110818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:06.860025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:06.490406Z","time spent":"369.505945ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:06.860073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.972236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:07:06.860122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.472489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T17:07:06.860152Z","caller":"traceutil/trace.go:171","msg":"trace[1525372603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"118.105458ms","start":"2026-04-23T17:07:06.742028Z","end":"2026-04-23T17:07:06.860134Z","steps":["trace[1525372603] 'agreement among raft nodes before linearized reading' (duration: 117.945135ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:06.860212Z","caller":"traceutil/trace.go:171","msg":"trace[572990684] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1272; }","duration":"344.606071ms","start":"2026-04-23T17:07:06.515574Z","end":"2026-04-23T17:07:06.86018Z","steps":["trace[572990684] 'agreement among raft nodes before linearized reading' (duration: 344.459989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:06.86027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:06.515554Z","time spent":"344.706163ms","remote":"127.0.0.1:44116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-23T17:07:07.130233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.576567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T17:07:07.13032Z","caller":"traceutil/trace.go:171","msg":"trace[1486106444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"202.737081ms","start":"2026-04-23T17:07:06.927565Z","end":"2026-04-23T17:07:07.130302Z","steps":["trace[1486106444] 'range keys from in-memory index tree' (duration: 202.358243ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:07.238519Z","caller":"traceutil/trace.go:171","msg":"trace[1894581303] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"103.46802ms","start":"2026-04-23T17:07:07.135023Z","end":"2026-04-23T17:07:07.238491Z","steps":["trace[1894581303] 'process raft request' (duration: 103.265407ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:07.581356Z","caller":"traceutil/trace.go:171","msg":"trace[1296399249] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"247.557534ms","start":"2026-04-23T17:07:07.333764Z","end":"2026-04-23T17:07:07.581322Z","steps":["trace[1296399249] 'process raft request' (duration: 157.706043ms)","trace[1296399249] 'compare' (duration: 89.517625ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:09.163997Z","caller":"traceutil/trace.go:171","msg":"trace[1438237183] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"242.636569ms","start":"2026-04-23T17:07:08.921334Z","end":"2026-04-23T17:07:09.16397Z","steps":["trace[1438237183] 'read index received' (duration: 242.466446ms)","trace[1438237183] 'applied index is now lower than readState.Index' (duration: 169.123µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:09.164112Z","caller":"traceutil/trace.go:171","msg":"trace[2032509572] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"295.299271ms","start":"2026-04-23T17:07:08.868782Z","end":"2026-04-23T17:07:09.164081Z","steps":["trace[2032509572] 'process raft request' (duration: 295.042677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:09.164128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.774903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:09.164181Z","caller":"traceutil/trace.go:171","msg":"trace[1533899933] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1278; }","duration":"242.849285ms","start":"2026-04-23T17:07:08.92132Z","end":"2026-04-23T17:07:09.164169Z","steps":["trace[1533899933] 'agreement among raft nodes before linearized reading' (duration: 242.749732ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:10.739705Z","caller":"traceutil/trace.go:171","msg":"trace[814954789] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"428.978128ms","start":"2026-04-23T17:07:10.310701Z","end":"2026-04-23T17:07:10.739679Z","steps":["trace[814954789] 'process raft request' (duration: 428.824755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:10.739981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:10.310684Z","time spent":"429.094541ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:11.583434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.703068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:07:11.583551Z","caller":"traceutil/trace.go:171","msg":"trace[1611231069] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"285.056577ms","start":"2026-04-23T17:07:11.298478Z","end":"2026-04-23T17:07:11.583535Z","steps":["trace[1611231069] 'read index received' (duration: 102.189975ms)","trace[1611231069] 'applied index is now lower than readState.Index' (duration: 182.865332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:11.5837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.229521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T17:07:11.583732Z","caller":"traceutil/trace.go:171","msg":"trace[260604339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"285.297382ms","start":"2026-04-23T17:07:11.298426Z","end":"2026-04-23T17:07:11.583724Z","steps":["trace[260604339] 'agreement among raft nodes before linearized reading' (duration: 285.16303ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:11.583843Z","caller":"traceutil/trace.go:171","msg":"trace[1822541528] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"311.914879ms","start":"2026-04-23T17:07:11.271917Z","end":"2026-04-23T17:07:11.583832Z","steps":["trace[1822541528] 'process raft request' (duration: 128.737771ms)","trace[1822541528] 'compare' (duration: 182.553496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:11.583897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:11.271898Z","time spent":"311.97043ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:12.121199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.843366ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T17:07:12.121303Z","caller":"traceutil/trace.go:171","msg":"trace[628253917] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"306.325733ms","start":"2026-04-23T17:07:11.814959Z","end":"2026-04-23T17:07:12.121284Z","steps":["trace[628253917] 'read index received' (duration: 170.405924ms)","trace[628253917] 'applied index is now lower than readState.Index' (duration: 135.918389ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:12.121463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.483396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:12.121534Z","caller":"traceutil/trace.go:171","msg":"trace[491692032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"306.592287ms","start":"2026-04-23T17:07:11.814923Z","end":"2026-04-23T17:07:12.121516Z","steps":["trace[491692032] 'agreement among raft nodes before linearized reading' (duration: 306.449135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:12.121566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.339ms","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-04-23T17:07:12.121578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:11.814912Z","time spent":"306.658608ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T17:07:12.121619Z","caller":"traceutil/trace.go:171","msg":"trace[686147277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"101.424102ms","start":"2026-04-23T17:07:12.020179Z","end":"2026-04-23T17:07:12.121603Z","steps":["trace[686147277] 'agreement among raft nodes before linearized reading' (duration: 101.233458ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:12.327317Z","caller":"traceutil/trace.go:171","msg":"trace[620253473] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"201.317263ms","start":"2026-04-23T17:07:12.125974Z","end":"2026-04-23T17:07:12.327292Z","steps":["trace[620253473] 'process raft request' (duration: 201.14341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:13.180415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.580842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:07:13.180551Z","caller":"traceutil/trace.go:171","msg":"trace[992859339] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"282.860745ms","start":"2026-04-23T17:07:12.89765Z","end":"2026-04-23T17:07:13.180511Z","steps":["trace[992859339] 'process raft request' (duration: 179.08835ms)","trace[992859339] 'compare' (duration: 103.362988ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:13.433837Z","caller":"traceutil/trace.go:171","msg":"trace[268647771] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"189.10051ms","start":"2026-04-23T17:07:13.244698Z","end":"2026-04-23T17:07:13.433799Z","steps":["trace[268647771] 'process raft request' (duration: 129.657799ms)","trace[268647771] 'compare' (duration: 59.321449ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:13.780819Z","caller":"traceutil/trace.go:171","msg":"trace[1443903560] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"121.86037ms","start":"2026-04-23T17:07:13.65893Z","end":"2026-04-23T17:07:13.780791Z","steps":["trace[1443903560] 'process raft request' (duration: 121.168937ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:15.027043Z","caller":"traceutil/trace.go:171","msg":"trace[962100029] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"206.602524ms","start":"2026-04-23T17:07:14.820416Z","end":"2026-04-23T17:07:15.027018Z","steps":["trace[962100029] 'process raft request' (duration: 206.448661ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:15.95877Z","caller":"traceutil/trace.go:171","msg":"trace[110968571] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"168.355586ms","start":"2026-04-23T17:07:15.790385Z","end":"2026-04-23T17:07:15.95874Z","steps":["trace[110968571] 'read index received' (duration: 168.171552ms)","trace[110968571] 'applied index is now lower than readState.Index' (duration: 182.214µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:15.958966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.500538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:15.959037Z","caller":"traceutil/trace.go:171","msg":"trace[1949532457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"168.664722ms","start":"2026-04-23T17:07:15.790361Z","end":"2026-04-23T17:07:15.959026Z","steps":["trace[1949532457] 'agreement among raft nodes before linearized reading' (duration: 168.490608ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:15.959138Z","caller":"traceutil/trace.go:171","msg":"trace[954598509] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"173.094206ms","start":"2026-04-23T17:07:15.786013Z","end":"2026-04-23T17:07:15.959107Z","steps":["trace[954598509] 'process raft request' (duration: 172.523825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:16.615717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.430521ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-23T17:07:17.078128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.841025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:17.078249Z","caller":"traceutil/trace.go:171","msg":"trace[2085737461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"336.026438ms","start":"2026-04-23T17:07:16.742188Z","end":"2026-04-23T17:07:17.078214Z","steps":["trace[2085737461] 'range keys from in-memory index tree' (duration: 335.707242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:17.078302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:16.742155Z","time spent":"336.134389ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T17:07:17.707914Z","caller":"traceutil/trace.go:171","msg":"trace[2130302305] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"181.889983ms","start":"2026-04-23T17:07:17.526001Z","end":"2026-04-23T17:07:17.707891Z","steps":["trace[2130302305] 'read index received' (duration: 181.691069ms)","trace[2130302305] 'applied index is now lower than readState.Index' (duration: 197.714µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:17.707964Z","caller":"traceutil/trace.go:171","msg":"trace[1379124632] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"220.498119ms","start":"2026-04-23T17:07:17.487435Z","end":"2026-04-23T17:07:17.707933Z","steps":["trace[1379124632] 'process raft request' (duration: 220.321486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:17.708217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.055326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:07:17.7083Z","caller":"traceutil/trace.go:171","msg":"trace[2031752570] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1305; }","duration":"182.310821ms","start":"2026-04-23T17:07:17.525971Z","end":"2026-04-23T17:07:17.708282Z","steps":["trace[2031752570] 'agreement among raft nodes before linearized reading' (duration: 182.041996ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:18.014646Z","caller":"traceutil/trace.go:171","msg":"trace[565771804] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"301.9771ms","start":"2026-04-23T17:07:17.712643Z","end":"2026-04-23T17:07:18.01462Z","steps":["trace[565771804] 'process raft request' (duration: 301.711574ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:18.014638Z","caller":"traceutil/trace.go:171","msg":"trace[930976431] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"100.733748ms","start":"2026-04-23T17:07:17.913879Z","end":"2026-04-23T17:07:18.014613Z","steps":["trace[930976431] 'read index received' (duration: 100.522754ms)","trace[930976431] 'applied index is now lower than readState.Index' (duration: 209.794µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:18.014788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:17.712624Z","time spent":"302.087732ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:18.014806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.917221ms","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-04-23T17:07:18.014867Z","caller":"traceutil/trace.go:171","msg":"trace[63053043] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1307; }","duration":"101.026074ms","start":"2026-04-23T17:07:17.913822Z","end":"2026-04-23T17:07:18.014848Z","steps":["trace[63053043] 'agreement among raft nodes before linearized reading' (duration: 100.868931ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:18.014885Z","caller":"traceutil/trace.go:171","msg":"trace[88160758] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"302.099343ms","start":"2026-04-23T17:07:17.712658Z","end":"2026-04-23T17:07:18.014757Z","steps":["trace[88160758] 'process raft request' (duration: 301.891729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:18.015043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:17.71264Z","time spent":"302.314726ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:19.979179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.887322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T17:07:19.97929Z","caller":"traceutil/trace.go:171","msg":"trace[1082966112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"267.047925ms","start":"2026-04-23T17:07:19.712218Z","end":"2026-04-23T17:07:19.979265Z","steps":["trace[1082966112] 'range keys from in-memory index tree' (duration: 266.691078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:19.979277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.837519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:19.97937Z","caller":"traceutil/trace.go:171","msg":"trace[570764621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"237.978721ms","start":"2026-04-23T17:07:19.74137Z","end":"2026-04-23T17:07:19.979348Z","steps":["trace[570764621] 'range keys from in-memory index tree' (duration: 237.750727ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:20.20054Z","caller":"traceutil/trace.go:171","msg":"trace[1061409800] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"124.763375ms","start":"2026-04-23T17:07:20.075749Z","end":"2026-04-23T17:07:20.200513Z","steps":["trace[1061409800] 'read index received' (duration: 81.322398ms)","trace[1061409800] 'applied index is now lower than readState.Index' (duration: 43.439637ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:20.200677Z","caller":"traceutil/trace.go:171","msg":"trace[3538794] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"162.928642ms","start":"2026-04-23T17:07:20.037716Z","end":"2026-04-23T17:07:20.200645Z","steps":["trace[3538794] 'process raft request' (duration: 119.318411ms)","trace[3538794] 'compare' (duration: 43.201513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:20.200957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.804782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T17:07:20.20143Z","caller":"traceutil/trace.go:171","msg":"trace[1639710078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"182.361823ms","start":"2026-04-23T17:07:20.019047Z","end":"2026-04-23T17:07:20.201409Z","steps":["trace[1639710078] 'agreement among raft nodes before linearized reading' (duration: 181.73336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:20.201015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.941354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T17:07:20.201588Z","caller":"traceutil/trace.go:171","msg":"trace[916145415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1313; }","duration":"182.516056ms","start":"2026-04-23T17:07:20.019017Z","end":"2026-04-23T17:07:20.201533Z","steps":["trace[916145415] 'agreement among raft nodes before linearized reading' (duration: 181.858173ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:20.564051Z","caller":"traceutil/trace.go:171","msg":"trace[2143541578] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"141.970724ms","start":"2026-04-23T17:07:20.422057Z","end":"2026-04-23T17:07:20.564027Z","steps":["trace[2143541578] 'process raft request' (duration: 141.82532ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:22.187292Z","caller":"traceutil/trace.go:171","msg":"trace[2115857278] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"102.282268ms","start":"2026-04-23T17:07:22.084972Z","end":"2026-04-23T17:07:22.187254Z","steps":["trace[2115857278] 'process raft request' (duration: 102.021873ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:23.377354Z","caller":"traceutil/trace.go:171","msg":"trace[968953455] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"176.798116ms","start":"2026-04-23T17:07:23.20051Z","end":"2026-04-23T17:07:23.377308Z","steps":["trace[968953455] 'process raft request' (duration: 176.384338ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:23.547281Z","caller":"traceutil/trace.go:171","msg":"trace[1881996056] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"165.816637ms","start":"2026-04-23T17:07:23.381407Z","end":"2026-04-23T17:07:23.547224Z","steps":["trace[1881996056] 'process raft request' (duration: 161.876101ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:24.428353Z","caller":"traceutil/trace.go:171","msg":"trace[1208650456] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"132.469211ms","start":"2026-04-23T17:07:24.295863Z","end":"2026-04-23T17:07:24.428332Z","steps":["trace[1208650456] 'read index received' (duration: 43.975266ms)","trace[1208650456] 'applied index is now lower than readState.Index' (duration: 88.492995ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:24.428548Z","caller":"traceutil/trace.go:171","msg":"trace[1664031602] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"186.445311ms","start":"2026-04-23T17:07:24.242092Z","end":"2026-04-23T17:07:24.428538Z","steps":["trace[1664031602] 'process raft request' (duration: 97.796072ms)","trace[1664031602] 'compare' (duration: 88.347842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:24.428846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.948241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T17:07:24.428957Z","caller":"traceutil/trace.go:171","msg":"trace[1232825154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"133.093474ms","start":"2026-04-23T17:07:24.295837Z","end":"2026-04-23T17:07:24.428931Z","steps":["trace[1232825154] 'agreement among raft nodes before linearized reading' (duration: 132.766938ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:24.775328Z","caller":"traceutil/trace.go:171","msg":"trace[1278858269] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"133.845968ms","start":"2026-04-23T17:07:24.641465Z","end":"2026-04-23T17:07:24.775311Z","steps":["trace[1278858269] 'process raft request' (duration: 133.749476ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:25.734448Z","caller":"traceutil/trace.go:171","msg":"trace[1482495817] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"178.627811ms","start":"2026-04-23T17:07:25.555797Z","end":"2026-04-23T17:07:25.734425Z","steps":["trace[1482495817] 'process raft request' (duration: 178.489338ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:26.318608Z","caller":"traceutil/trace.go:171","msg":"trace[1348764264] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"101.834469ms","start":"2026-04-23T17:07:26.216739Z","end":"2026-04-23T17:07:26.318573Z","steps":["trace[1348764264] 'process raft request' (duration: 101.620514ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:26.624727Z","caller":"traceutil/trace.go:171","msg":"trace[1244473729] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1388; }","duration":"171.523706ms","start":"2026-04-23T17:07:26.45318Z","end":"2026-04-23T17:07:26.624704Z","steps":["trace[1244473729] 'read index received' (duration: 32.080671ms)","trace[1244473729] 'applied index is now lower than readState.Index' (duration: 139.441595ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:26.624803Z","caller":"traceutil/trace.go:171","msg":"trace[236634975] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"190.636189ms","start":"2026-04-23T17:07:26.434141Z","end":"2026-04-23T17:07:26.624777Z","steps":["trace[236634975] 'process raft request' (duration: 190.398815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:26.625021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.830601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T17:07:26.625179Z","caller":"traceutil/trace.go:171","msg":"trace[1996589280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"172.021704ms","start":"2026-04-23T17:07:26.453135Z","end":"2026-04-23T17:07:26.625157Z","steps":["trace[1996589280] 'agreement among raft nodes before linearized reading' (duration: 171.706258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:26.640236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.619727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T17:07:26.640298Z","caller":"traceutil/trace.go:171","msg":"trace[54742284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"129.707439ms","start":"2026-04-23T17:07:26.510574Z","end":"2026-04-23T17:07:26.640282Z","steps":["trace[54742284] 'agreement among raft nodes before linearized reading' (duration: 129.534626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:26.784185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.075693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:07:26.784437Z","caller":"traceutil/trace.go:171","msg":"trace[317600843] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"140.2854ms","start":"2026-04-23T17:07:26.64413Z","end":"2026-04-23T17:07:26.784415Z","steps":["trace[317600843] 'process raft request' (duration: 140.230059ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:26.784474Z","caller":"traceutil/trace.go:171","msg":"trace[1415649487] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"155.300457ms","start":"2026-04-23T17:07:26.629146Z","end":"2026-04-23T17:07:26.784446Z","steps":["trace[1415649487] 'process raft request' (duration: 42.884327ms)","trace[1415649487] 'compare' (duration: 111.947261ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:26.784636Z","caller":"traceutil/trace.go:171","msg":"trace[328170258] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"142.38421ms","start":"2026-04-23T17:07:26.64224Z","end":"2026-04-23T17:07:26.784625Z","steps":["trace[328170258] 'read index received' (duration: 29.740266ms)","trace[328170258] 'applied index is now lower than readState.Index' (duration: 112.642384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:26.784749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.495762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-23T17:07:26.784781Z","caller":"traceutil/trace.go:171","msg":"trace[809203470] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1335; }","duration":"142.557223ms","start":"2026-04-23T17:07:26.642216Z","end":"2026-04-23T17:07:26.784773Z","steps":["trace[809203470] 'agreement among raft nodes before linearized reading' (duration: 142.480051ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:26.927196Z","caller":"traceutil/trace.go:171","msg":"trace[465844102] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"137.169162ms","start":"2026-04-23T17:07:26.789997Z","end":"2026-04-23T17:07:26.927167Z","steps":["trace[465844102] 'process raft request' (duration: 125.733854ms)","trace[465844102] 'compare' (duration: 11.287985ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:28.431379Z","caller":"traceutil/trace.go:171","msg":"trace[1790764012] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"105.242794ms","start":"2026-04-23T17:07:28.32611Z","end":"2026-04-23T17:07:28.431353Z","steps":["trace[1790764012] 'process raft request' (duration: 105.05229ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:28.913551Z","caller":"traceutil/trace.go:171","msg":"trace[891109400] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"120.754249ms","start":"2026-04-23T17:07:28.792764Z","end":"2026-04-23T17:07:28.913518Z","steps":["trace[891109400] 'process raft request' (duration: 72.223835ms)","trace[891109400] 'compare' (duration: 48.353331ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:28.91369Z","caller":"traceutil/trace.go:171","msg":"trace[1643479988] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"120.905912ms","start":"2026-04-23T17:07:28.792761Z","end":"2026-04-23T17:07:28.913667Z","steps":["trace[1643479988] 'process raft request' (duration: 120.699898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:29.123561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.893425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:07:29.123667Z","caller":"traceutil/trace.go:171","msg":"trace[1138783504] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"188.292536ms","start":"2026-04-23T17:07:28.935357Z","end":"2026-04-23T17:07:29.123649Z","steps":["trace[1138783504] 'process raft request' (duration: 81.229027ms)","trace[1138783504] 'compare' (duration: 106.60456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:30.863587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.455782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:30.863783Z","caller":"traceutil/trace.go:171","msg":"trace[1925922690] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1345; }","duration":"206.708557ms","start":"2026-04-23T17:07:30.657046Z","end":"2026-04-23T17:07:30.863755Z","steps":["trace[1925922690] 'count revisions from in-memory index tree' (duration: 206.343099ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:30.863933Z","caller":"traceutil/trace.go:171","msg":"trace[1726186630] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"134.818157ms","start":"2026-04-23T17:07:30.729078Z","end":"2026-04-23T17:07:30.863896Z","steps":["trace[1726186630] 'process raft request' (duration: 83.53649ms)","trace[1726186630] 'compare' (duration: 50.971821ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:31.245976Z","caller":"traceutil/trace.go:171","msg":"trace[1237920332] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"324.230643ms","start":"2026-04-23T17:07:30.921719Z","end":"2026-04-23T17:07:31.24595Z","steps":["trace[1237920332] 'process raft request' (duration: 324.006809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:31.246245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:30.921704Z","time spent":"324.350406ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:07:31.47488Z","caller":"traceutil/trace.go:171","msg":"trace[1995523419] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1404; }","duration":"522.988567ms","start":"2026-04-23T17:07:30.951865Z","end":"2026-04-23T17:07:31.474853Z","steps":["trace[1995523419] 'read index received' (duration: 293.848245ms)","trace[1995523419] 'applied index is now lower than readState.Index' (duration: 229.139252ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:31.475015Z","caller":"traceutil/trace.go:171","msg":"trace[19007374] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"553.276184ms","start":"2026-04-23T17:07:30.921723Z","end":"2026-04-23T17:07:31.474999Z","steps":["trace[19007374] 'process raft request' (duration: 459.412337ms)","trace[19007374] 'compare' (duration: 93.610032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:31.475153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:30.921704Z","time spent":"553.339126ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:07:31.475461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.547587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:07:31.475465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.583609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:07:31.475538Z","caller":"traceutil/trace.go:171","msg":"trace[1969230426] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1348; }","duration":"223.68151ms","start":"2026-04-23T17:07:31.251839Z","end":"2026-04-23T17:07:31.47552Z","steps":["trace[1969230426] 'agreement among raft nodes before linearized reading' (duration: 223.543307ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:31.475548Z","caller":"traceutil/trace.go:171","msg":"trace[1974295087] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1348; }","duration":"523.696951ms","start":"2026-04-23T17:07:30.951832Z","end":"2026-04-23T17:07:31.475529Z","steps":["trace[1974295087] 'agreement among raft nodes before linearized reading' (duration: 523.507787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:31.47559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:30.95182Z","time spent":"523.761533ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:07:31.475704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.585118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T17:07:31.475789Z","caller":"traceutil/trace.go:171","msg":"trace[864197841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"348.20356ms","start":"2026-04-23T17:07:31.127568Z","end":"2026-04-23T17:07:31.475772Z","steps":["trace[864197841] 'agreement among raft nodes before linearized reading' (duration: 347.469826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:31.47583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:07:31.12755Z","time spent":"348.270791ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T17:07:33.103343Z","caller":"traceutil/trace.go:171","msg":"trace[902823304] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"196.295387ms","start":"2026-04-23T17:07:32.907024Z","end":"2026-04-23T17:07:33.103319Z","steps":["trace[902823304] 'read index received' (duration: 196.000682ms)","trace[902823304] 'applied index is now lower than readState.Index' (duration: 293.765µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:33.103416Z","caller":"traceutil/trace.go:171","msg":"trace[391830908] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"233.2502ms","start":"2026-04-23T17:07:32.870134Z","end":"2026-04-23T17:07:33.103384Z","steps":["trace[391830908] 'process raft request' (duration: 233.043766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:33.103476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.435339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:07:33.103515Z","caller":"traceutil/trace.go:171","msg":"trace[849078430] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1352; }","duration":"196.529991ms","start":"2026-04-23T17:07:32.906975Z","end":"2026-04-23T17:07:33.103505Z","steps":["trace[849078430] 'agreement among raft nodes before linearized reading' (duration: 196.43874ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:33.561429Z","caller":"traceutil/trace.go:171","msg":"trace[446097617] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"270.846656ms","start":"2026-04-23T17:07:33.290522Z","end":"2026-04-23T17:07:33.561369Z","steps":["trace[446097617] 'process raft request' (duration: 270.53331ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:33.69916Z","caller":"traceutil/trace.go:171","msg":"trace[303956114] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"133.782208ms","start":"2026-04-23T17:07:33.565349Z","end":"2026-04-23T17:07:33.699132Z","steps":["trace[303956114] 'process raft request' (duration: 117.031269ms)","trace[303956114] 'compare' (duration: 16.624126ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:33.702186Z","caller":"traceutil/trace.go:171","msg":"trace[328451614] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"136.588111ms","start":"2026-04-23T17:07:33.565581Z","end":"2026-04-23T17:07:33.702169Z","steps":["trace[328451614] 'process raft request' (duration: 136.457809ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:33.702268Z","caller":"traceutil/trace.go:171","msg":"trace[1737025059] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1412; }","duration":"105.957298ms","start":"2026-04-23T17:07:33.596298Z","end":"2026-04-23T17:07:33.702256Z","steps":["trace[1737025059] 'read index received' (duration: 86.09235ms)","trace[1737025059] 'applied index is now lower than readState.Index' (duration: 19.863778ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:33.702361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.039519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:33.702388Z","caller":"traceutil/trace.go:171","msg":"trace[877682812] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1356; }","duration":"106.1128ms","start":"2026-04-23T17:07:33.596267Z","end":"2026-04-23T17:07:33.70238Z","steps":["trace[877682812] 'agreement among raft nodes before linearized reading' (duration: 106.044789ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:33.702503Z","caller":"traceutil/trace.go:171","msg":"trace[1945884458] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"117.586209ms","start":"2026-04-23T17:07:33.584889Z","end":"2026-04-23T17:07:33.702475Z","steps":["trace[1945884458] 'process raft request' (duration: 117.250793ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:35.278923Z","caller":"traceutil/trace.go:171","msg":"trace[1244018914] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"169.321544ms","start":"2026-04-23T17:07:35.109571Z","end":"2026-04-23T17:07:35.278893Z","steps":["trace[1244018914] 'process raft request' (duration: 169.15378ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:35.67435Z","caller":"traceutil/trace.go:171","msg":"trace[1532201164] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"103.4741ms","start":"2026-04-23T17:07:35.570854Z","end":"2026-04-23T17:07:35.674328Z","steps":["trace[1532201164] 'process raft request' (duration: 103.345947ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:35.892267Z","caller":"traceutil/trace.go:171","msg":"trace[1603035322] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"186.007521ms","start":"2026-04-23T17:07:35.706217Z","end":"2026-04-23T17:07:35.892225Z","steps":["trace[1603035322] 'process raft request' (duration: 150.832681ms)","trace[1603035322] 'compare' (duration: 34.962706ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:35.89245Z","caller":"traceutil/trace.go:171","msg":"trace[356063433] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"185.741546ms","start":"2026-04-23T17:07:35.706576Z","end":"2026-04-23T17:07:35.892317Z","steps":["trace[356063433] 'read index received' (duration: 150.383903ms)","trace[356063433] 'applied index is now lower than readState.Index' (duration: 35.347883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:35.892595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.996581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T17:07:35.892725Z","caller":"traceutil/trace.go:171","msg":"trace[2025041722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"186.130544ms","start":"2026-04-23T17:07:35.706549Z","end":"2026-04-23T17:07:35.89268Z","steps":["trace[2025041722] 'agreement among raft nodes before linearized reading' (duration: 185.842377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:35.892809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.947694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:07:35.892887Z","caller":"traceutil/trace.go:171","msg":"trace[979624906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"152.111716ms","start":"2026-04-23T17:07:35.740762Z","end":"2026-04-23T17:07:35.892874Z","steps":["trace[979624906] 'agreement among raft nodes before linearized reading' (duration: 151.884231ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:07:36.798493Z","caller":"traceutil/trace.go:171","msg":"trace[328403603] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"150.469625ms","start":"2026-04-23T17:07:36.647981Z","end":"2026-04-23T17:07:36.798451Z","steps":["trace[328403603] 'process raft request' (duration: 150.20142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:07:38.118857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.059468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:07:38.119719Z","caller":"traceutil/trace.go:171","msg":"trace[1988161132] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"169.132461ms","start":"2026-04-23T17:07:37.950507Z","end":"2026-04-23T17:07:38.119639Z","steps":["trace[1988161132] 'process raft request' (duration: 61.211326ms)","trace[1988161132] 'compare' (duration: 106.933095ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:07:38.119862Z","caller":"traceutil/trace.go:171","msg":"trace[1511122375] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"135.899388ms","start":"2026-04-23T17:07:37.983902Z","end":"2026-04-23T17:07:38.119801Z","steps":["trace[1511122375] 'read index received' (duration: 27.880181ms)","trace[1511122375] 'applied index is now lower than readState.Index' (duration: 108.010246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:07:38.120198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.259684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T17:07:38.12043Z","caller":"traceutil/trace.go:171","msg":"trace[1646164389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"136.567571ms","start":"2026-04-23T17:07:37.983847Z","end":"2026-04-23T17:07:38.120415Z","steps":["trace[1646164389] 'agreement among raft nodes before linearized reading' (duration: 136.109071ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:08:42.84468Z","caller":"traceutil/trace.go:171","msg":"trace[1414943526] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1653; }","duration":"130.213569ms","start":"2026-04-23T17:08:42.714427Z","end":"2026-04-23T17:08:42.84464Z","steps":["trace[1414943526] 'read index received' (duration: 130.134307ms)","trace[1414943526] 'applied index is now lower than readState.Index' (duration: 78.032µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:08:42.844751Z","caller":"traceutil/trace.go:171","msg":"trace[1265769760] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"300.716095ms","start":"2026-04-23T17:08:42.544013Z","end":"2026-04-23T17:08:42.844729Z","steps":["trace[1265769760] 'process raft request' (duration: 300.44678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:42.844849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.386752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T17:08:42.844893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:42.543992Z","time spent":"300.820217ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:08:42.844911Z","caller":"traceutil/trace.go:171","msg":"trace[1654038388] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1581; }","duration":"130.522075ms","start":"2026-04-23T17:08:42.714374Z","end":"2026-04-23T17:08:42.844896Z","steps":["trace[1654038388] 'agreement among raft nodes before linearized reading' (duration: 130.383992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:42.845028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.69374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:08:42.845126Z","caller":"traceutil/trace.go:171","msg":"trace[1796561691] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1581; }","duration":"113.843624ms","start":"2026-04-23T17:08:42.731265Z","end":"2026-04-23T17:08:42.845109Z","steps":["trace[1796561691] 'agreement among raft nodes before linearized reading' (duration: 113.689841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:42.845143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.244083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:08:42.845237Z","caller":"traceutil/trace.go:171","msg":"trace[660381022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1581; }","duration":"104.410117ms","start":"2026-04-23T17:08:42.740805Z","end":"2026-04-23T17:08:42.845215Z","steps":["trace[660381022] 'agreement among raft nodes before linearized reading' (duration: 104.205343ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:08:44.023564Z","caller":"traceutil/trace.go:171","msg":"trace[2121511557] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"789.228428ms","start":"2026-04-23T17:08:43.234305Z","end":"2026-04-23T17:08:44.023533Z","steps":["trace[2121511557] 'process raft request' (duration: 789.072345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:44.023752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:43.234287Z","time spent":"789.37221ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:08:45.234795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.216758Z","time spent":"1.018028908s","remote":"127.0.0.1:53052","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T17:08:45.236352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.42736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:08:45.236463Z","caller":"traceutil/trace.go:171","msg":"trace[2066281669] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1654; }","duration":"1.305271035s","start":"2026-04-23T17:08:43.931168Z","end":"2026-04-23T17:08:45.236439Z","steps":["trace[2066281669] 'read index received' (duration: 92.290507ms)","trace[2066281669] 'applied index is now lower than readState.Index' (duration: 1.212978178s)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:08:45.236557Z","caller":"traceutil/trace.go:171","msg":"trace[1733825505] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"1.535106016s","start":"2026-04-23T17:08:43.701394Z","end":"2026-04-23T17:08:45.2365Z","steps":["trace[1733825505] 'process raft request' (duration: 937.823029ms)","trace[1733825505] 'compare' (duration: 595.817928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:08:45.236639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305466039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:08:45.236695Z","caller":"traceutil/trace.go:171","msg":"trace[1119288664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1583; }","duration":"1.305555501s","start":"2026-04-23T17:08:43.931129Z","end":"2026-04-23T17:08:45.236684Z","steps":["trace[1119288664] 'agreement among raft nodes before linearized reading' (duration: 1.305459699s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:45.236736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:43.931113Z","time spent":"1.305614472s","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:08:45.236745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:43.701368Z","time spent":"1.535283239s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:08:45.237185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.549632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:08:45.237214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160217384s","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-04-23T17:08:45.237231Z","caller":"traceutil/trace.go:171","msg":"trace[1060128685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1583; }","duration":"495.630864ms","start":"2026-04-23T17:08:44.741588Z","end":"2026-04-23T17:08:45.237219Z","steps":["trace[1060128685] 'agreement among raft nodes before linearized reading' (duration: 495.544252ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:08:45.23725Z","caller":"traceutil/trace.go:171","msg":"trace[1461494196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1583; }","duration":"1.160281334s","start":"2026-04-23T17:08:44.07696Z","end":"2026-04-23T17:08:45.237241Z","steps":["trace[1461494196] 'agreement among raft nodes before linearized reading' (duration: 1.160142951s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:45.237262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.741567Z","time spent":"495.688575ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:08:45.23728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.076945Z","time spent":"1.160329365s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:08:45.237413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.49334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T17:08:45.237422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.759492ms","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":"info","ts":"2026-04-23T17:08:45.237451Z","caller":"traceutil/trace.go:171","msg":"trace[869052538] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1583; }","duration":"418.828543ms","start":"2026-04-23T17:08:44.818614Z","end":"2026-04-23T17:08:45.237442Z","steps":["trace[869052538] 'agreement among raft nodes before linearized reading' (duration: 418.781852ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:08:45.237443Z","caller":"traceutil/trace.go:171","msg":"trace[1517498995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1583; }","duration":"803.549881ms","start":"2026-04-23T17:08:44.433885Z","end":"2026-04-23T17:08:45.237435Z","steps":["trace[1517498995] 'agreement among raft nodes before linearized reading' (duration: 803.48049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:45.237485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.818591Z","time spent":"418.886764ms","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:08:45.237503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.433868Z","time spent":"803.627622ms","remote":"127.0.0.1:44222","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-04-23T17:08:45.237636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.831392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T17:08:45.237661Z","caller":"traceutil/trace.go:171","msg":"trace[1956446216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1583; }","duration":"978.892942ms","start":"2026-04-23T17:08:44.258761Z","end":"2026-04-23T17:08:45.237654Z","steps":["trace[1956446216] 'agreement among raft nodes before linearized reading' (duration: 978.830541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:45.23768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.258741Z","time spent":"978.935014ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:08:45.237614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.924651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:08:45.237797Z","caller":"traceutil/trace.go:171","msg":"trace[394543423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1583; }","duration":"388.155216ms","start":"2026-04-23T17:08:44.849621Z","end":"2026-04-23T17:08:45.237776Z","steps":["trace[394543423] 'agreement among raft nodes before linearized reading' (duration: 387.600005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:08:45.237956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:08:44.849589Z","time spent":"388.35261ms","remote":"127.0.0.1:44222","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-04-23T17:09:07.478427Z","caller":"traceutil/trace.go:171","msg":"trace[1353434340] linearizableReadLoop","detail":"{readStateIndex:1736; appliedIndex:1735; }","duration":"141.744245ms","start":"2026-04-23T17:09:07.336663Z","end":"2026-04-23T17:09:07.478407Z","steps":["trace[1353434340] 'read index received' (duration: 141.608441ms)","trace[1353434340] 'applied index is now lower than readState.Index' (duration: 135.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:07.47856Z","caller":"traceutil/trace.go:171","msg":"trace[190762498] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"170.118672ms","start":"2026-04-23T17:09:07.3084Z","end":"2026-04-23T17:09:07.478518Z","steps":["trace[190762498] 'process raft request' (duration: 169.881156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:07.478639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.518184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:09:07.478675Z","caller":"traceutil/trace.go:171","msg":"trace[1203034952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1658; }","duration":"137.608737ms","start":"2026-04-23T17:09:07.341054Z","end":"2026-04-23T17:09:07.478663Z","steps":["trace[1203034952] 'agreement among raft nodes before linearized reading' (duration: 137.483305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:07.478676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.00005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:09:07.478749Z","caller":"traceutil/trace.go:171","msg":"trace[517581019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1658; }","duration":"142.081022ms","start":"2026-04-23T17:09:07.336648Z","end":"2026-04-23T17:09:07.478729Z","steps":["trace[517581019] 'agreement among raft nodes before linearized reading' (duration: 141.882368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:07.478844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.672507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:09:07.478876Z","caller":"traceutil/trace.go:171","msg":"trace[2128860375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1658; }","duration":"135.724448ms","start":"2026-04-23T17:09:07.343144Z","end":"2026-04-23T17:09:07.478869Z","steps":["trace[2128860375] 'agreement among raft nodes before linearized reading' (duration: 135.641756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:07.478856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.944998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:09:07.479059Z","caller":"traceutil/trace.go:171","msg":"trace[2059013900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1658; }","duration":"132.07849ms","start":"2026-04-23T17:09:07.346848Z","end":"2026-04-23T17:09:07.478927Z","steps":["trace[2059013900] 'agreement among raft nodes before linearized reading' (duration: 131.763563ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:07.684003Z","caller":"traceutil/trace.go:171","msg":"trace[1938358536] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"200.712846ms","start":"2026-04-23T17:09:07.483262Z","end":"2026-04-23T17:09:07.683975Z","steps":["trace[1938358536] 'process raft request' (duration: 195.790552ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:07.91828Z","caller":"traceutil/trace.go:171","msg":"trace[1511998947] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"434.478748ms","start":"2026-04-23T17:09:07.48376Z","end":"2026-04-23T17:09:07.918239Z","steps":["trace[1511998947] 'process raft request' (duration: 431.577077ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:07.918312Z","caller":"traceutil/trace.go:171","msg":"trace[1359740973] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"434.412096ms","start":"2026-04-23T17:09:07.483871Z","end":"2026-04-23T17:09:07.918283Z","steps":["trace[1359740973] 'process raft request' (duration: 434.245643ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:07.91845Z","caller":"traceutil/trace.go:171","msg":"trace[1905276997] linearizableReadLoop","detail":"{readStateIndex:1740; appliedIndex:1737; }","duration":"176.22788ms","start":"2026-04-23T17:09:07.742196Z","end":"2026-04-23T17:09:07.918424Z","steps":["trace[1905276997] 'read index received' (duration: 173.181216ms)","trace[1905276997] 'applied index is now lower than readState.Index' (duration: 3.044784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:07.918493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:07.483741Z","time spent":"434.620991ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:07.918515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:07.483865Z","time spent":"434.5425ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:07.918575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.358333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:07.918609Z","caller":"traceutil/trace.go:171","msg":"trace[391648391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1662; }","duration":"176.457754ms","start":"2026-04-23T17:09:07.742142Z","end":"2026-04-23T17:09:07.9186Z","steps":["trace[391648391] 'agreement among raft nodes before linearized reading' (duration: 176.373073ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:07.918701Z","caller":"traceutil/trace.go:171","msg":"trace[666255754] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"434.499297ms","start":"2026-04-23T17:09:07.484186Z","end":"2026-04-23T17:09:07.918686Z","steps":["trace[666255754] 'process raft request' (duration: 434.040268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:07.918785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:07.484162Z","time spent":"434.580519ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:10.021603Z","caller":"traceutil/trace.go:171","msg":"trace[630931191] linearizableReadLoop","detail":"{readStateIndex:1742; appliedIndex:1741; }","duration":"344.84361ms","start":"2026-04-23T17:09:09.676739Z","end":"2026-04-23T17:09:10.021583Z","steps":["trace[630931191] 'read index received' (duration: 344.665956ms)","trace[630931191] 'applied index is now lower than readState.Index' (duration: 176.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:10.021681Z","caller":"traceutil/trace.go:171","msg":"trace[1058234159] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"537.131869ms","start":"2026-04-23T17:09:09.484511Z","end":"2026-04-23T17:09:10.021643Z","steps":["trace[1058234159] 'process raft request' (duration: 536.949265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:10.022293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:09.484479Z","time spent":"537.733601ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:10.021822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.072964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:10.022436Z","caller":"traceutil/trace.go:171","msg":"trace[1427926550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1664; }","duration":"345.726049ms","start":"2026-04-23T17:09:09.676685Z","end":"2026-04-23T17:09:10.022411Z","steps":["trace[1427926550] 'agreement among raft nodes before linearized reading' (duration: 345.082834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:10.022129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.867583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:09:10.022528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:09.676666Z","time spent":"345.836291ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T17:09:10.02254Z","caller":"traceutil/trace.go:171","msg":"trace[2126764593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1664; }","duration":"281.306403ms","start":"2026-04-23T17:09:09.741211Z","end":"2026-04-23T17:09:10.022517Z","steps":["trace[2126764593] 'agreement among raft nodes before linearized reading' (duration: 280.891443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:10.022172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.721496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:09:10.022849Z","caller":"traceutil/trace.go:171","msg":"trace[1413499682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1664; }","duration":"334.504322ms","start":"2026-04-23T17:09:09.688329Z","end":"2026-04-23T17:09:10.022833Z","steps":["trace[1413499682] 'agreement among raft nodes before linearized reading' (duration: 333.657184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:10.022926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:09.688318Z","time spent":"334.593215ms","remote":"127.0.0.1:44222","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-04-23T17:09:10.20991Z","caller":"traceutil/trace.go:171","msg":"trace[55957451] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"182.701657ms","start":"2026-04-23T17:09:10.027186Z","end":"2026-04-23T17:09:10.209888Z","steps":["trace[55957451] 'process raft request' (duration: 134.019302ms)","trace[55957451] 'compare' (duration: 48.578583ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:10.257636Z","caller":"traceutil/trace.go:171","msg":"trace[792071338] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"229.705497ms","start":"2026-04-23T17:09:10.027898Z","end":"2026-04-23T17:09:10.257604Z","steps":["trace[792071338] 'process raft request' (duration: 229.491362ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:10.257662Z","caller":"traceutil/trace.go:171","msg":"trace[767579483] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"229.714957ms","start":"2026-04-23T17:09:10.027925Z","end":"2026-04-23T17:09:10.25764Z","steps":["trace[767579483] 'process raft request' (duration: 229.576363ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:10.257715Z","caller":"traceutil/trace.go:171","msg":"trace[846189656] transaction","detail":"{read_only:false; response_revision:1668; number_of_response:1; }","duration":"229.276798ms","start":"2026-04-23T17:09:10.028405Z","end":"2026-04-23T17:09:10.257682Z","steps":["trace[846189656] 'process raft request' (duration: 229.142174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:11.881994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.258214ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T17:09:11.882232Z","caller":"traceutil/trace.go:171","msg":"trace[1778605633] linearizableReadLoop","detail":"{readStateIndex:1748; appliedIndex:1747; }","duration":"138.403634ms","start":"2026-04-23T17:09:11.743807Z","end":"2026-04-23T17:09:11.882211Z","steps":["trace[1778605633] 'read index received' (duration: 39.521µs)","trace[1778605633] 'applied index is now lower than readState.Index' (duration: 138.361573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:11.882413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.590597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:11.882516Z","caller":"traceutil/trace.go:171","msg":"trace[1074547146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1669; }","duration":"138.72853ms","start":"2026-04-23T17:09:11.74376Z","end":"2026-04-23T17:09:11.882489Z","steps":["trace[1074547146] 'agreement among raft nodes before linearized reading' (duration: 138.598808ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:12.438727Z","caller":"traceutil/trace.go:171","msg":"trace[1251256812] linearizableReadLoop","detail":"{readStateIndex:1749; appliedIndex:1748; }","duration":"225.087488ms","start":"2026-04-23T17:09:12.213615Z","end":"2026-04-23T17:09:12.438703Z","steps":["trace[1251256812] 'read index received' (duration: 224.984096ms)","trace[1251256812] 'applied index is now lower than readState.Index' (duration: 102.312µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:12.438957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.343683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:09:12.43902Z","caller":"traceutil/trace.go:171","msg":"trace[1125576731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1670; }","duration":"225.455856ms","start":"2026-04-23T17:09:12.213551Z","end":"2026-04-23T17:09:12.439007Z","steps":["trace[1125576731] 'agreement among raft nodes before linearized reading' (duration: 225.268631ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:12.439279Z","caller":"traceutil/trace.go:171","msg":"trace[759674048] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"409.897209ms","start":"2026-04-23T17:09:12.028854Z","end":"2026-04-23T17:09:12.438751Z","steps":["trace[759674048] 'process raft request' (duration: 409.695105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:12.439458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.028832Z","time spent":"410.508162ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:12.939726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209149346774514008,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T17:09:13.428662Z","caller":"traceutil/trace.go:171","msg":"trace[1464570625] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"1.067178577s","start":"2026-04-23T17:09:12.361459Z","end":"2026-04-23T17:09:13.428638Z","steps":["trace[1464570625] 'process raft request' (duration: 1.067013333s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:13.428802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.361442Z","time spent":"1.067286349s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:13.440163Z","caller":"traceutil/trace.go:171","msg":"trace[1425667137] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1749; }","duration":"1.001312481s","start":"2026-04-23T17:09:12.438814Z","end":"2026-04-23T17:09:13.440126Z","steps":["trace[1425667137] 'read index received' (duration: 989.653235ms)","trace[1425667137] 'applied index is now lower than readState.Index' (duration: 11.658216ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:13.440341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178291506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:09:13.440393Z","caller":"traceutil/trace.go:171","msg":"trace[343544563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1672; }","duration":"1.178375738s","start":"2026-04-23T17:09:12.262005Z","end":"2026-04-23T17:09:13.440381Z","steps":["trace[343544563] 'agreement among raft nodes before linearized reading' (duration: 1.178212535s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:13.440431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.26199Z","time spent":"1.178433699s","remote":"127.0.0.1:44222","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-04-23T17:09:13.44045Z","caller":"traceutil/trace.go:171","msg":"trace[923329279] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"995.269243ms","start":"2026-04-23T17:09:12.445138Z","end":"2026-04-23T17:09:13.440407Z","steps":["trace[923329279] 'process raft request' (duration: 994.879064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:13.440554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178910829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:09:13.440606Z","caller":"traceutil/trace.go:171","msg":"trace[1238686333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1672; }","duration":"1.179017661s","start":"2026-04-23T17:09:12.261577Z","end":"2026-04-23T17:09:13.440595Z","steps":["trace[1238686333] 'agreement among raft nodes before linearized reading' (duration: 1.178649273s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:13.440649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.445122Z","time spent":"995.421917ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:13.440645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.261559Z","time spent":"1.179078113s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:13.440686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178842197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T17:09:13.440455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163828582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T17:09:13.440829Z","caller":"traceutil/trace.go:171","msg":"trace[1092899169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1672; }","duration":"1.164303282s","start":"2026-04-23T17:09:12.276516Z","end":"2026-04-23T17:09:13.44082Z","steps":["trace[1092899169] 'agreement among raft nodes before linearized reading' (duration: 1.163628089s)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:13.440784Z","caller":"traceutil/trace.go:171","msg":"trace[53644749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1672; }","duration":"1.17897991s","start":"2026-04-23T17:09:12.261792Z","end":"2026-04-23T17:09:13.440772Z","steps":["trace[53644749] 'agreement among raft nodes before linearized reading' (duration: 1.178801637s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:13.440895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.276506Z","time spent":"1.164356663s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:13.440899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:12.261772Z","time spent":"1.179110793s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T17:09:16.361892Z","caller":"traceutil/trace.go:171","msg":"trace[1661836064] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"120.359504ms","start":"2026-04-23T17:09:16.241509Z","end":"2026-04-23T17:09:16.361869Z","steps":["trace[1661836064] 'process raft request' (duration: 120.19083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:24.850383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.78159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:24.850478Z","caller":"traceutil/trace.go:171","msg":"trace[1053516887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1762; }","duration":"108.907223ms","start":"2026-04-23T17:09:24.741538Z","end":"2026-04-23T17:09:24.850445Z","steps":["trace[1053516887] 'range keys from in-memory index tree' (duration: 108.635367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:26.079575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209149346774514209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:09:26.580016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209149346774514209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:09:26.679876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.194327813s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T17:09:26.680319Z","caller":"traceutil/trace.go:171","msg":"trace[1761414218] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"1.194822553s","start":"2026-04-23T17:09:25.485466Z","end":"2026-04-23T17:09:26.680288Z","steps":["trace[1761414218] 'process raft request' (duration: 1.194649059s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:26.680477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:25.485446Z","time spent":"1.194947586s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:27.67579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.896366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:09:27.67603Z","caller":"traceutil/trace.go:171","msg":"trace[1280374453] linearizableReadLoop","detail":"{readStateIndex:1847; appliedIndex:1845; }","duration":"2.097445206s","start":"2026-04-23T17:09:25.578563Z","end":"2026-04-23T17:09:27.676008Z","steps":["trace[1280374453] 'read index received' (duration: 1.101642382s)","trace[1280374453] 'applied index is now lower than readState.Index' (duration: 995.801284ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:27.676102Z","caller":"traceutil/trace.go:171","msg":"trace[1830775556] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"1.075524852s","start":"2026-04-23T17:09:26.600564Z","end":"2026-04-23T17:09:27.676089Z","steps":["trace[1830775556] 'process raft request' (duration: 1.075356039s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.676206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:26.600541Z","time spent":"1.075608353s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:27.676289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097714982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:09:27.676336Z","caller":"traceutil/trace.go:171","msg":"trace[1237347276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1765; }","duration":"2.097787213s","start":"2026-04-23T17:09:25.578532Z","end":"2026-04-23T17:09:27.676319Z","steps":["trace[1237347276] 'agreement among raft nodes before linearized reading' (duration: 2.097616309s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.676377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:25.578518Z","time spent":"2.097848214s","remote":"127.0.0.1:44222","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-04-23T17:09:27.676524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.448564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:27.67657Z","caller":"traceutil/trace.go:171","msg":"trace[1179647744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1765; }","duration":"935.530596ms","start":"2026-04-23T17:09:26.741026Z","end":"2026-04-23T17:09:27.676557Z","steps":["trace[1179647744] 'agreement among raft nodes before linearized reading' (duration: 935.459174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.676607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:26.741013Z","time spent":"935.584066ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:09:27.676614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.768879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:09:27.676679Z","caller":"traceutil/trace.go:171","msg":"trace[407801435] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1765; }","duration":"973.856112ms","start":"2026-04-23T17:09:26.702794Z","end":"2026-04-23T17:09:27.67665Z","steps":["trace[407801435] 'agreement among raft nodes before linearized reading' (duration: 973.76148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.67673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:26.702769Z","time spent":"973.950065ms","remote":"127.0.0.1:44502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-23T17:09:27.676037Z","caller":"traceutil/trace.go:171","msg":"trace[967708917] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"1.135281s","start":"2026-04-23T17:09:26.540729Z","end":"2026-04-23T17:09:27.67601Z","steps":["trace[967708917] 'process raft request' (duration: 755.536096ms)","trace[967708917] 'compare' (duration: 378.10443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:27.676807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707946156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:27.676861Z","caller":"traceutil/trace.go:171","msg":"trace[1941790131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1765; }","duration":"1.708036638s","start":"2026-04-23T17:09:25.968807Z","end":"2026-04-23T17:09:27.676844Z","steps":["trace[1941790131] 'agreement among raft nodes before linearized reading' (duration: 1.707956537s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.676904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:25.96879Z","time spent":"1.708103859s","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:09:27.676935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.174793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:09:27.676975Z","caller":"traceutil/trace.go:171","msg":"trace[1191615244] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1765; }","duration":"294.258965ms","start":"2026-04-23T17:09:27.382703Z","end":"2026-04-23T17:09:27.676962Z","steps":["trace[1191615244] 'agreement among raft nodes before linearized reading' (duration: 294.184783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.677027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.091379049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T17:09:27.676853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:26.540706Z","time spent":"1.136098348s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:27.677067Z","caller":"traceutil/trace.go:171","msg":"trace[1272733082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1765; }","duration":"2.09144843s","start":"2026-04-23T17:09:25.585606Z","end":"2026-04-23T17:09:27.677054Z","steps":["trace[1272733082] 'agreement among raft nodes before linearized reading' (duration: 2.091350968s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.677098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:25.585599Z","time spent":"2.091493651s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:27.677224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.091694945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T17:09:27.677232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.581814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:09:27.677211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299293653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.76\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T17:09:27.677288Z","caller":"traceutil/trace.go:171","msg":"trace[1420325645] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1765; }","duration":"676.677576ms","start":"2026-04-23T17:09:27.000595Z","end":"2026-04-23T17:09:27.677273Z","steps":["trace[1420325645] 'agreement among raft nodes before linearized reading' (duration: 676.591604ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:27.677316Z","caller":"traceutil/trace.go:171","msg":"trace[295340054] range","detail":"{range_begin:/registry/masterleases/199.19.213.76; range_end:; response_count:1; response_revision:1765; }","duration":"1.299442826s","start":"2026-04-23T17:09:26.377848Z","end":"2026-04-23T17:09:27.677291Z","steps":["trace[295340054] 'agreement among raft nodes before linearized reading' (duration: 1.29910517s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.677335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:27.000578Z","time spent":"676.743197ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:09:27.677367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:26.377833Z","time spent":"1.299524628s","remote":"127.0.0.1:43996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.76\" "} {"level":"warn","ts":"2026-04-23T17:09:27.676494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097409855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:09:27.677461Z","caller":"traceutil/trace.go:171","msg":"trace[1618256669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1765; }","duration":"2.098423417s","start":"2026-04-23T17:09:25.579029Z","end":"2026-04-23T17:09:27.677452Z","steps":["trace[1618256669] 'agreement among raft nodes before linearized reading' (duration: 2.097244422s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.677501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:25.579021Z","time spent":"2.098470008s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:27.677609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.912529ms","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-04-23T17:09:27.677248Z","caller":"traceutil/trace.go:171","msg":"trace[1882189350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1765; }","duration":"2.091738186s","start":"2026-04-23T17:09:25.585503Z","end":"2026-04-23T17:09:27.677241Z","steps":["trace[1882189350] 'agreement among raft nodes before linearized reading' (duration: 2.091666285s)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:27.677634Z","caller":"traceutil/trace.go:171","msg":"trace[1946891103] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1765; }","duration":"892.959859ms","start":"2026-04-23T17:09:26.784666Z","end":"2026-04-23T17:09:27.677626Z","steps":["trace[1946891103] 'agreement among raft nodes before linearized reading' (duration: 892.901738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:27.677662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:26.784653Z","time spent":"893.00267ms","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:09:27.67766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:25.585494Z","time spent":"2.092148965s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T17:09:39.881855Z","caller":"traceutil/trace.go:171","msg":"trace[2082171768] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"104.174884ms","start":"2026-04-23T17:09:39.777655Z","end":"2026-04-23T17:09:39.88183Z","steps":["trace[2082171768] 'read index received' (duration: 103.975889ms)","trace[2082171768] 'applied index is now lower than readState.Index' (duration: 197.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:39.88197Z","caller":"traceutil/trace.go:171","msg":"trace[401107712] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"125.700037ms","start":"2026-04-23T17:09:39.756227Z","end":"2026-04-23T17:09:39.881927Z","steps":["trace[401107712] 'process raft request' (duration: 125.435241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:39.882107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.052997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:09:39.882141Z","caller":"traceutil/trace.go:171","msg":"trace[1534292474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1805; }","duration":"100.121019ms","start":"2026-04-23T17:09:39.78201Z","end":"2026-04-23T17:09:39.882131Z","steps":["trace[1534292474] 'agreement among raft nodes before linearized reading' (duration: 99.964926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:39.882639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.956379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:09:39.882708Z","caller":"traceutil/trace.go:171","msg":"trace[1610536310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1805; }","duration":"105.062692ms","start":"2026-04-23T17:09:39.777626Z","end":"2026-04-23T17:09:39.882688Z","steps":["trace[1610536310] 'agreement among raft nodes before linearized reading' (duration: 104.346198ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:40.021638Z","caller":"traceutil/trace.go:171","msg":"trace[1710472425] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"134.295726ms","start":"2026-04-23T17:09:39.88731Z","end":"2026-04-23T17:09:40.021606Z","steps":["trace[1710472425] 'process raft request' (duration: 82.702831ms)","trace[1710472425] 'compare' (duration: 51.469123ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:40.402952Z","caller":"traceutil/trace.go:171","msg":"trace[2085853483] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1892; }","duration":"404.126538ms","start":"2026-04-23T17:09:39.998803Z","end":"2026-04-23T17:09:40.40293Z","steps":["trace[2085853483] 'read index received' (duration: 362.207476ms)","trace[2085853483] 'applied index is now lower than readState.Index' (duration: 41.917922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:40.403133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.270461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:40.40319Z","caller":"traceutil/trace.go:171","msg":"trace[507379326] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1809; }","duration":"404.405134ms","start":"2026-04-23T17:09:39.99876Z","end":"2026-04-23T17:09:40.403165Z","steps":["trace[507379326] 'agreement among raft nodes before linearized reading' (duration: 404.255771ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:40.403174Z","caller":"traceutil/trace.go:171","msg":"trace[815522445] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"514.069573ms","start":"2026-04-23T17:09:39.889073Z","end":"2026-04-23T17:09:40.403142Z","steps":["trace[815522445] 'process raft request' (duration: 472.041518ms)","trace[815522445] 'compare' (duration: 41.34276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:40.403229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:39.998743Z","time spent":"404.474055ms","remote":"127.0.0.1:33518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:09:40.403328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:39.88905Z","time spent":"514.189016ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:40.403397Z","caller":"traceutil/trace.go:171","msg":"trace[1598778824] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"514.252587ms","start":"2026-04-23T17:09:39.889093Z","end":"2026-04-23T17:09:40.403345Z","steps":["trace[1598778824] 'process raft request' (duration: 513.730336ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:40.403449Z","caller":"traceutil/trace.go:171","msg":"trace[439627424] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"514.213476ms","start":"2026-04-23T17:09:39.889205Z","end":"2026-04-23T17:09:40.403418Z","steps":["trace[439627424] 'process raft request' (duration: 513.685856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:40.403549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:39.889077Z","time spent":"514.387739ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:40.403605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:39.88918Z","time spent":"514.336928ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:40.901666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.172012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:09:40.901758Z","caller":"traceutil/trace.go:171","msg":"trace[1891919761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1809; }","duration":"160.322494ms","start":"2026-04-23T17:09:40.741416Z","end":"2026-04-23T17:09:40.901738Z","steps":["trace[1891919761] 'range keys from in-memory index tree' (duration: 159.954147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:40.901699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.459139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:40.901853Z","caller":"traceutil/trace.go:171","msg":"trace[353905100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1809; }","duration":"160.657912ms","start":"2026-04-23T17:09:40.741179Z","end":"2026-04-23T17:09:40.901837Z","steps":["trace[353905100] 'range keys from in-memory index tree' (duration: 160.398468ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:41.028774Z","caller":"traceutil/trace.go:171","msg":"trace[641991365] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"122.774435ms","start":"2026-04-23T17:09:40.90598Z","end":"2026-04-23T17:09:41.028754Z","steps":["trace[641991365] 'process raft request' (duration: 122.647773ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:42.329047Z","caller":"traceutil/trace.go:171","msg":"trace[337481106] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"303.222732ms","start":"2026-04-23T17:09:42.0258Z","end":"2026-04-23T17:09:42.329023Z","steps":["trace[337481106] 'read index received' (duration: 303.038659ms)","trace[337481106] 'applied index is now lower than readState.Index' (duration: 182.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:42.329177Z","caller":"traceutil/trace.go:171","msg":"trace[1138718152] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"440.685477ms","start":"2026-04-23T17:09:41.888481Z","end":"2026-04-23T17:09:42.329166Z","steps":["trace[1138718152] 'process raft request' (duration: 440.38645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:42.329304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.834848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:09:42.329395Z","caller":"traceutil/trace.go:171","msg":"trace[2092512925] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1811; }","duration":"219.925889ms","start":"2026-04-23T17:09:42.109437Z","end":"2026-04-23T17:09:42.329362Z","steps":["trace[2092512925] 'agreement among raft nodes before linearized reading' (duration: 219.797877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:42.329599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:41.888457Z","time spent":"440.746978ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:42.329659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.864376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:09:42.329689Z","caller":"traceutil/trace.go:171","msg":"trace[856417771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1811; }","duration":"303.946358ms","start":"2026-04-23T17:09:42.025734Z","end":"2026-04-23T17:09:42.329681Z","steps":["trace[856417771] 'agreement among raft nodes before linearized reading' (duration: 303.841056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:42.329717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:42.025715Z","time spent":"303.994199ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T17:09:42.896183Z","caller":"traceutil/trace.go:171","msg":"trace[1487968878] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1897; }","duration":"488.869443ms","start":"2026-04-23T17:09:42.407293Z","end":"2026-04-23T17:09:42.896163Z","steps":["trace[1487968878] 'read index received' (duration: 421.951874ms)","trace[1487968878] 'applied index is now lower than readState.Index' (duration: 66.916779ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:42.896225Z","caller":"traceutil/trace.go:171","msg":"trace[493319851] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"561.732527ms","start":"2026-04-23T17:09:42.334465Z","end":"2026-04-23T17:09:42.896197Z","steps":["trace[493319851] 'process raft request' (duration: 494.849168ms)","trace[493319851] 'compare' (duration: 66.462509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:42.896338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:42.334444Z","time spent":"561.831578ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:42.896613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.574736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:09:42.896729Z","caller":"traceutil/trace.go:171","msg":"trace[1372158984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1812; }","duration":"488.717738ms","start":"2026-04-23T17:09:42.407997Z","end":"2026-04-23T17:09:42.896714Z","steps":["trace[1372158984] 'agreement among raft nodes before linearized reading' (duration: 488.507734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:42.89677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:42.407989Z","time spent":"488.771049ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:42.897368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.978905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:09:42.897412Z","caller":"traceutil/trace.go:171","msg":"trace[66775558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1812; }","duration":"490.149959ms","start":"2026-04-23T17:09:42.407249Z","end":"2026-04-23T17:09:42.897399Z","steps":["trace[66775558] 'agreement among raft nodes before linearized reading' (duration: 489.012896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:42.897477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:42.407231Z","time spent":"490.20704ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:42.897728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.035877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:09:42.897847Z","caller":"traceutil/trace.go:171","msg":"trace[42508037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1812; }","duration":"490.192789ms","start":"2026-04-23T17:09:42.407644Z","end":"2026-04-23T17:09:42.897837Z","steps":["trace[42508037] 'agreement among raft nodes before linearized reading' (duration: 488.640036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:42.897891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:42.407621Z","time spent":"490.261771ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T17:09:48.879436Z","caller":"traceutil/trace.go:171","msg":"trace[66489412] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"138.499895ms","start":"2026-04-23T17:09:48.740888Z","end":"2026-04-23T17:09:48.879388Z","steps":["trace[66489412] 'read index received' (duration: 78.38982ms)","trace[66489412] 'applied index is now lower than readState.Index' (duration: 60.108405ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:48.879488Z","caller":"traceutil/trace.go:171","msg":"trace[1324922767] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"525.768109ms","start":"2026-04-23T17:09:48.353685Z","end":"2026-04-23T17:09:48.879453Z","steps":["trace[1324922767] 'process raft request' (duration: 465.605242ms)","trace[1324922767] 'compare' (duration: 59.912972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:48.879582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.671349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:48.87963Z","caller":"traceutil/trace.go:171","msg":"trace[292861884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"138.763571ms","start":"2026-04-23T17:09:48.740853Z","end":"2026-04-23T17:09:48.879616Z","steps":["trace[292861884] 'agreement among raft nodes before linearized reading' (duration: 138.666919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:48.879635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:48.353647Z","time spent":"525.905752ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:49.190548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.931185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-23T17:09:49.190601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.299824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:49.190631Z","caller":"traceutil/trace.go:171","msg":"trace[1845792848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1873; }","duration":"187.050278ms","start":"2026-04-23T17:09:49.003562Z","end":"2026-04-23T17:09:49.190613Z","steps":["trace[1845792848] 'range keys from in-memory index tree' (duration: 186.756232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:49.190622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.241638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:09:49.190674Z","caller":"traceutil/trace.go:171","msg":"trace[904882626] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1873; }","duration":"197.444437ms","start":"2026-04-23T17:09:48.993211Z","end":"2026-04-23T17:09:49.190656Z","steps":["trace[904882626] 'count revisions from in-memory index tree' (duration: 197.196731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:49.190668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.019159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:09:49.190698Z","caller":"traceutil/trace.go:171","msg":"trace[341452238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1873; }","duration":"193.35622ms","start":"2026-04-23T17:09:48.997323Z","end":"2026-04-23T17:09:49.190679Z","steps":["trace[341452238] 'range keys from in-memory index tree' (duration: 193.042714ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:49.190721Z","caller":"traceutil/trace.go:171","msg":"trace[126222685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1873; }","duration":"188.1103ms","start":"2026-04-23T17:09:49.002598Z","end":"2026-04-23T17:09:49.190708Z","steps":["trace[126222685] 'range keys from in-memory index tree' (duration: 187.857495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:49.190861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.034649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T17:09:49.190887Z","caller":"traceutil/trace.go:171","msg":"trace[1157503499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1873; }","duration":"188.081589ms","start":"2026-04-23T17:09:49.002796Z","end":"2026-04-23T17:09:49.190878Z","steps":["trace[1157503499] 'range keys from in-memory index tree' (duration: 187.944737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:49.190996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.657819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T17:09:49.191134Z","caller":"traceutil/trace.go:171","msg":"trace[1586647906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"129.796032ms","start":"2026-04-23T17:09:49.061275Z","end":"2026-04-23T17:09:49.191071Z","steps":["trace[1586647906] 'range keys from in-memory index tree' (duration: 129.463285ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:49.399548Z","caller":"traceutil/trace.go:171","msg":"trace[241984266] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"203.939253ms","start":"2026-04-23T17:09:49.195583Z","end":"2026-04-23T17:09:49.399522Z","steps":["trace[241984266] 'process raft request' (duration: 203.833441ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:49.399554Z","caller":"traceutil/trace.go:171","msg":"trace[5630458] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"204.540916ms","start":"2026-04-23T17:09:49.19498Z","end":"2026-04-23T17:09:49.399521Z","steps":["trace[5630458] 'process raft request' (duration: 204.286421ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:49.399819Z","caller":"traceutil/trace.go:171","msg":"trace[583458564] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"203.874422ms","start":"2026-04-23T17:09:49.195921Z","end":"2026-04-23T17:09:49.399795Z","steps":["trace[583458564] 'process raft request' (duration: 203.564805ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:49.400012Z","caller":"traceutil/trace.go:171","msg":"trace[304981287] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"203.7618ms","start":"2026-04-23T17:09:49.19624Z","end":"2026-04-23T17:09:49.400002Z","steps":["trace[304981287] 'process raft request' (duration: 203.436182ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.009719Z","caller":"traceutil/trace.go:171","msg":"trace[805980906] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"124.807117ms","start":"2026-04-23T17:09:50.884886Z","end":"2026-04-23T17:09:51.009694Z","steps":["trace[805980906] 'process raft request' (duration: 124.019981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.768961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.429992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:09:51.769022Z","caller":"traceutil/trace.go:171","msg":"trace[76561194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1879; }","duration":"364.523385ms","start":"2026-04-23T17:09:51.404484Z","end":"2026-04-23T17:09:51.769007Z","steps":["trace[76561194] 'range keys from in-memory index tree' (duration: 364.245649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.76905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.404469Z","time spent":"364.573845ms","remote":"127.0.0.1:44222","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-04-23T17:09:51.769186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.506569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:51.769291Z","caller":"traceutil/trace.go:171","msg":"trace[415357966] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1879; }","duration":"418.685673ms","start":"2026-04-23T17:09:51.35058Z","end":"2026-04-23T17:09:51.769265Z","steps":["trace[415357966] 'count revisions from in-memory index tree' (duration: 418.433188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.769329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.80523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-23T17:09:51.769361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.45414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-23T17:09:51.769412Z","caller":"traceutil/trace.go:171","msg":"trace[1963407031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1879; }","duration":"364.935743ms","start":"2026-04-23T17:09:51.404458Z","end":"2026-04-23T17:09:51.769394Z","steps":["trace[1963407031] 'range keys from in-memory index tree' (duration: 364.553345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.769358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.350568Z","time spent":"418.773335ms","remote":"127.0.0.1:33444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:09:51.769455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.40444Z","time spent":"365.006095ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:51.769492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.819653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:09:51.769517Z","caller":"traceutil/trace.go:171","msg":"trace[1087407708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1879; }","duration":"644.849524ms","start":"2026-04-23T17:09:51.12466Z","end":"2026-04-23T17:09:51.769509Z","steps":["trace[1087407708] 'count revisions from in-memory index tree' (duration: 644.747891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.769501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.721547ms","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-04-23T17:09:51.769544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.12461Z","time spent":"644.927505ms","remote":"127.0.0.1:33664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:09:51.769547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.605407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T17:09:51.769571Z","caller":"traceutil/trace.go:171","msg":"trace[835735986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1879; }","duration":"364.8093ms","start":"2026-04-23T17:09:51.40474Z","end":"2026-04-23T17:09:51.769549Z","steps":["trace[835735986] 'range keys from in-memory index tree' (duration: 364.583185ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.76961Z","caller":"traceutil/trace.go:171","msg":"trace[44757163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1879; }","duration":"365.683668ms","start":"2026-04-23T17:09:51.403898Z","end":"2026-04-23T17:09:51.769581Z","steps":["trace[44757163] 'range keys from in-memory index tree' (duration: 365.485193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.769626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.404729Z","time spent":"364.886112ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:09:51.769649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.40388Z","time spent":"365.76165ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:51.769652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.098405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T17:09:51.76969Z","caller":"traceutil/trace.go:171","msg":"trace[609958603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1879; }","duration":"536.167326ms","start":"2026-04-23T17:09:51.233512Z","end":"2026-04-23T17:09:51.769679Z","steps":["trace[609958603] 'range keys from in-memory index tree' (duration: 535.938142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.76941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.699478ms","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":"info","ts":"2026-04-23T17:09:51.769747Z","caller":"traceutil/trace.go:171","msg":"trace[1735119019] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1879; }","duration":"366.101677ms","start":"2026-04-23T17:09:51.403637Z","end":"2026-04-23T17:09:51.769739Z","steps":["trace[1735119019] 'count revisions from in-memory index tree' (duration: 365.599016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.769772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.403616Z","time spent":"366.148217ms","remote":"127.0.0.1:44342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-23T17:09:51.769415Z","caller":"traceutil/trace.go:171","msg":"trace[1768587776] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1879; }","duration":"352.529022ms","start":"2026-04-23T17:09:51.416873Z","end":"2026-04-23T17:09:51.769402Z","steps":["trace[1768587776] 'range keys from in-memory index tree' (duration: 352.295296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:51.769839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.416862Z","time spent":"352.96756ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T17:09:51.769716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:51.233495Z","time spent":"536.214537ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T17:09:51.907711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.992958ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T17:09:51.908084Z","caller":"traceutil/trace.go:171","msg":"trace[619435102] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"166.111777ms","start":"2026-04-23T17:09:51.741881Z","end":"2026-04-23T17:09:51.907993Z","steps":["trace[619435102] 'read index received' (duration: 43.935596ms)","trace[619435102] 'applied index is now lower than readState.Index' (duration: 122.174161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:51.908195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.304841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:51.908247Z","caller":"traceutil/trace.go:171","msg":"trace[1784840786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"166.403403ms","start":"2026-04-23T17:09:51.74183Z","end":"2026-04-23T17:09:51.908234Z","steps":["trace[1784840786] 'agreement among raft nodes before linearized reading' (duration: 166.308661ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.958648Z","caller":"traceutil/trace.go:171","msg":"trace[1860498313] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"184.379842ms","start":"2026-04-23T17:09:51.774231Z","end":"2026-04-23T17:09:51.958611Z","steps":["trace[1860498313] 'process raft request' (duration: 184.179017ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.958835Z","caller":"traceutil/trace.go:171","msg":"trace[707450897] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"184.008573ms","start":"2026-04-23T17:09:51.774811Z","end":"2026-04-23T17:09:51.95882Z","steps":["trace[707450897] 'process raft request' (duration: 183.735767ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.959Z","caller":"traceutil/trace.go:171","msg":"trace[602269607] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"184.704568ms","start":"2026-04-23T17:09:51.774276Z","end":"2026-04-23T17:09:51.958981Z","steps":["trace[602269607] 'process raft request' (duration: 184.180967ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.959039Z","caller":"traceutil/trace.go:171","msg":"trace[444043411] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"182.367268ms","start":"2026-04-23T17:09:51.776662Z","end":"2026-04-23T17:09:51.959029Z","steps":["trace[444043411] 'process raft request' (duration: 181.945579ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.958992Z","caller":"traceutil/trace.go:171","msg":"trace[1505415322] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"182.741376ms","start":"2026-04-23T17:09:51.776234Z","end":"2026-04-23T17:09:51.958975Z","steps":["trace[1505415322] 'process raft request' (duration: 182.344858ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:51.95927Z","caller":"traceutil/trace.go:171","msg":"trace[313596902] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"185.087527ms","start":"2026-04-23T17:09:51.774166Z","end":"2026-04-23T17:09:51.959253Z","steps":["trace[313596902] 'process raft request' (duration: 184.092255ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:53.758546Z","caller":"traceutil/trace.go:171","msg":"trace[773973495] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"743.034662ms","start":"2026-04-23T17:09:53.015488Z","end":"2026-04-23T17:09:53.758522Z","steps":["trace[773973495] 'process raft request' (duration: 742.719815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:53.758682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:53.015442Z","time spent":"743.171384ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:53.784531Z","caller":"traceutil/trace.go:171","msg":"trace[1636206371] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"391.977077ms","start":"2026-04-23T17:09:53.392533Z","end":"2026-04-23T17:09:53.78451Z","steps":["trace[1636206371] 'read index received' (duration: 366.49829ms)","trace[1636206371] 'applied index is now lower than readState.Index' (duration: 25.477447ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:09:53.784564Z","caller":"traceutil/trace.go:171","msg":"trace[2125736174] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"507.642735ms","start":"2026-04-23T17:09:53.276885Z","end":"2026-04-23T17:09:53.784527Z","steps":["trace[2125736174] 'process raft request' (duration: 507.509423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:53.784865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.314243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:09:53.784939Z","caller":"traceutil/trace.go:171","msg":"trace[101732414] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1889; }","duration":"392.397764ms","start":"2026-04-23T17:09:53.392522Z","end":"2026-04-23T17:09:53.78492Z","steps":["trace[101732414] 'agreement among raft nodes before linearized reading' (duration: 392.211781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:53.784928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:53.276862Z","time spent":"507.93843ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:09:53.785017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:53.392479Z","time spent":"392.493516ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T17:09:54.157462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.277443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:09:54.157634Z","caller":"traceutil/trace.go:171","msg":"trace[481853130] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"121.695425ms","start":"2026-04-23T17:09:54.035923Z","end":"2026-04-23T17:09:54.157618Z","steps":["trace[481853130] 'process raft request' (duration: 121.638904ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.157704Z","caller":"traceutil/trace.go:171","msg":"trace[1091099421] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"364.439932ms","start":"2026-04-23T17:09:53.793194Z","end":"2026-04-23T17:09:54.157634Z","steps":["trace[1091099421] 'process raft request' (duration: 250.941344ms)","trace[1091099421] 'compare' (duration: 113.186221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:54.157869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:53.793183Z","time spent":"364.614205ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:09:54.157943Z","caller":"traceutil/trace.go:171","msg":"trace[28071496] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"194.326641ms","start":"2026-04-23T17:09:53.963591Z","end":"2026-04-23T17:09:54.157917Z","steps":["trace[28071496] 'read index received' (duration: 80.556108ms)","trace[28071496] 'applied index is now lower than readState.Index' (duration: 113.766973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:54.15821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.606617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-23T17:09:54.158215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.941553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T17:09:54.158259Z","caller":"traceutil/trace.go:171","msg":"trace[404499583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"194.703798ms","start":"2026-04-23T17:09:53.963543Z","end":"2026-04-23T17:09:54.158247Z","steps":["trace[404499583] 'agreement among raft nodes before linearized reading' (duration: 194.504715ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.158278Z","caller":"traceutil/trace.go:171","msg":"trace[320513803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1891; }","duration":"194.046186ms","start":"2026-04-23T17:09:53.964213Z","end":"2026-04-23T17:09:54.158259Z","steps":["trace[320513803] 'agreement among raft nodes before linearized reading' (duration: 193.907113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:54.158443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.155678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-23T17:09:54.158441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.070046ms","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-04-23T17:09:54.158468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.061396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T17:09:54.158484Z","caller":"traceutil/trace.go:171","msg":"trace[353705922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"194.216429ms","start":"2026-04-23T17:09:53.964259Z","end":"2026-04-23T17:09:54.158475Z","steps":["trace[353705922] 'agreement among raft nodes before linearized reading' (duration: 194.114167ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.158513Z","caller":"traceutil/trace.go:171","msg":"trace[672643886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1891; }","duration":"194.114737ms","start":"2026-04-23T17:09:53.964381Z","end":"2026-04-23T17:09:54.158496Z","steps":["trace[672643886] 'agreement among raft nodes before linearized reading' (duration: 194.018085ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.158509Z","caller":"traceutil/trace.go:171","msg":"trace[1242214280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"194.171168ms","start":"2026-04-23T17:09:53.964321Z","end":"2026-04-23T17:09:54.158492Z","steps":["trace[1242214280] 'agreement among raft nodes before linearized reading' (duration: 193.960154ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.556875Z","caller":"traceutil/trace.go:171","msg":"trace[1883468549] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"394.329892ms","start":"2026-04-23T17:09:54.162521Z","end":"2026-04-23T17:09:54.556851Z","steps":["trace[1883468549] 'process raft request' (duration: 394.21012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:54.557071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.162499Z","time spent":"394.447655ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T17:09:54.607073Z","caller":"traceutil/trace.go:171","msg":"trace[1149415213] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"442.481461ms","start":"2026-04-23T17:09:54.164555Z","end":"2026-04-23T17:09:54.607037Z","steps":["trace[1149415213] 'process raft request' (duration: 442.170824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:54.607289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.164546Z","time spent":"442.639034ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:54.608184Z","caller":"traceutil/trace.go:171","msg":"trace[91284536] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"443.399288ms","start":"2026-04-23T17:09:54.164763Z","end":"2026-04-23T17:09:54.608162Z","steps":["trace[91284536] 'process raft request' (duration: 443.246105ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.608386Z","caller":"traceutil/trace.go:171","msg":"trace[233561983] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1981; }","duration":"443.757766ms","start":"2026-04-23T17:09:54.164608Z","end":"2026-04-23T17:09:54.608366Z","steps":["trace[233561983] 'read index received' (duration: 392.316203ms)","trace[233561983] 'applied index is now lower than readState.Index' (duration: 51.440053ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:09:54.608407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.164758Z","time spent":"443.603123ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T17:09:54.608584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.960899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-23T17:09:54.608638Z","caller":"traceutil/trace.go:171","msg":"trace[1690958063] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1899; }","duration":"444.03164ms","start":"2026-04-23T17:09:54.164589Z","end":"2026-04-23T17:09:54.608621Z","steps":["trace[1690958063] 'agreement among raft nodes before linearized reading' (duration: 443.847337ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.608674Z","caller":"traceutil/trace.go:171","msg":"trace[1639741464] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"443.977929ms","start":"2026-04-23T17:09:54.164679Z","end":"2026-04-23T17:09:54.608657Z","steps":["trace[1639741464] 'process raft request' (duration: 442.232876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:54.608686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.164584Z","time spent":"444.089261ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-23T17:09:54.608775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.164674Z","time spent":"444.038801ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:54.609004Z","caller":"traceutil/trace.go:171","msg":"trace[2085986493] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"337.424984ms","start":"2026-04-23T17:09:54.271543Z","end":"2026-04-23T17:09:54.608968Z","steps":["trace[2085986493] 'process raft request' (duration: 336.697119ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.608877Z","caller":"traceutil/trace.go:171","msg":"trace[67748852] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"443.517771ms","start":"2026-04-23T17:09:54.165341Z","end":"2026-04-23T17:09:54.608858Z","steps":["trace[67748852] 'process raft request' (duration: 442.795806ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.609189Z","caller":"traceutil/trace.go:171","msg":"trace[1943026438] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"442.773326ms","start":"2026-04-23T17:09:54.166393Z","end":"2026-04-23T17:09:54.609166Z","steps":["trace[1943026438] 'process raft request' (duration: 441.795237ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:09:54.609116Z","caller":"traceutil/trace.go:171","msg":"trace[1922091435] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"443.823316ms","start":"2026-04-23T17:09:54.165233Z","end":"2026-04-23T17:09:54.609056Z","steps":["trace[1922091435] 'process raft request' (duration: 442.846717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:09:54.609344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.166387Z","time spent":"442.869998ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:54.609432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.165223Z","time spent":"444.168563ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:54.609163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.271508Z","time spent":"337.567567ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T17:09:54.609392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:09:54.165337Z","time spent":"443.877927ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:09:54.78849Z","caller":"traceutil/trace.go:171","msg":"trace[1004612422] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"173.179097ms","start":"2026-04-23T17:09:54.615271Z","end":"2026-04-23T17:09:54.78845Z","steps":["trace[1004612422] 'process raft request' (duration: 124.438026ms)","trace[1004612422] 'compare' (duration: 48.562268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:10:07.261993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.107079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:10:07.262123Z","caller":"traceutil/trace.go:171","msg":"trace[1961793274] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1990; }","duration":"182.302152ms","start":"2026-04-23T17:10:07.079796Z","end":"2026-04-23T17:10:07.262098Z","steps":["trace[1961793274] 'count revisions from in-memory index tree' (duration: 181.931915ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:10:10.391885Z","caller":"traceutil/trace.go:171","msg":"trace[2009205247] linearizableReadLoop","detail":"{readStateIndex:2097; appliedIndex:2096; }","duration":"100.434168ms","start":"2026-04-23T17:10:10.291426Z","end":"2026-04-23T17:10:10.39186Z","steps":["trace[2009205247] 'read index received' (duration: 100.218314ms)","trace[2009205247] 'applied index is now lower than readState.Index' (duration: 214.514µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:10:10.39202Z","caller":"traceutil/trace.go:171","msg":"trace[1215493509] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"103.603708ms","start":"2026-04-23T17:10:10.288391Z","end":"2026-04-23T17:10:10.391995Z","steps":["trace[1215493509] 'process raft request' (duration: 103.252131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:10.392809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.798615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2722"} {"level":"info","ts":"2026-04-23T17:10:10.392973Z","caller":"traceutil/trace.go:171","msg":"trace[1539381557] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2005; }","duration":"101.545959ms","start":"2026-04-23T17:10:10.29139Z","end":"2026-04-23T17:10:10.392936Z","steps":["trace[1539381557] 'agreement among raft nodes before linearized reading' (duration: 100.650132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:12.579038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.381454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-23T17:10:12.579163Z","caller":"traceutil/trace.go:171","msg":"trace[492791733] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2016; }","duration":"182.560767ms","start":"2026-04-23T17:10:12.396587Z","end":"2026-04-23T17:10:12.579148Z","steps":["trace[492791733] 'range keys from in-memory index tree' (duration: 182.21618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:13.19467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209149346774514779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:10:13.695534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209149346774514779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T17:10:13.753088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06975414s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T17:10:14.481907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.556386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T17:10:14.482022Z","caller":"traceutil/trace.go:171","msg":"trace[1482600945] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"1.787466659s","start":"2026-04-23T17:10:12.694538Z","end":"2026-04-23T17:10:14.482005Z","steps":["trace[1482600945] 'read index received' (duration: 1.058697909s)","trace[1482600945] 'applied index is now lower than readState.Index' (duration: 728.76714ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:10:14.482117Z","caller":"traceutil/trace.go:171","msg":"trace[1954345373] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"1.798832216s","start":"2026-04-23T17:10:12.683237Z","end":"2026-04-23T17:10:14.482069Z","steps":["trace[1954345373] 'process raft request' (duration: 1.069992295s)","trace[1954345373] 'compare' (duration: 728.382512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T17:10:14.482295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787719484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-23T17:10:14.482353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:12.683216Z","time spent":"1.799002059s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:10:14.482413Z","caller":"traceutil/trace.go:171","msg":"trace[1672269380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2018; }","duration":"1.787881717s","start":"2026-04-23T17:10:12.694508Z","end":"2026-04-23T17:10:14.48239Z","steps":["trace[1672269380] 'agreement among raft nodes before linearized reading' (duration: 1.787551721s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.482459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:12.694492Z","time spent":"1.787959918s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T17:10:14.580189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.131033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T17:10:14.580296Z","caller":"traceutil/trace.go:171","msg":"trace[620205673] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2019; }","duration":"854.304687ms","start":"2026-04-23T17:10:13.725972Z","end":"2026-04-23T17:10:14.580276Z","steps":["trace[620205673] 'agreement among raft nodes before linearized reading' (duration: 854.081393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.580308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879806803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T17:10:14.580418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.426704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T17:10:14.580456Z","caller":"traceutil/trace.go:171","msg":"trace[1708921645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2019; }","duration":"838.461635ms","start":"2026-04-23T17:10:13.741984Z","end":"2026-04-23T17:10:14.580446Z","steps":["trace[1708921645] 'agreement among raft nodes before linearized reading' (duration: 838.402594ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:10:14.580411Z","caller":"traceutil/trace.go:171","msg":"trace[393578247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2019; }","duration":"1.879942896s","start":"2026-04-23T17:10:12.700451Z","end":"2026-04-23T17:10:14.580393Z","steps":["trace[393578247] 'agreement among raft nodes before linearized reading' (duration: 1.879673541s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.580497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881591347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T17:10:14.580502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:13.741948Z","time spent":"838.548156ms","remote":"127.0.0.1:43960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T17:10:14.580527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:12.700442Z","time spent":"1.880074838s","remote":"127.0.0.1:44222","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-04-23T17:10:14.58055Z","caller":"traceutil/trace.go:171","msg":"trace[491357370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2019; }","duration":"1.881653278s","start":"2026-04-23T17:10:12.698879Z","end":"2026-04-23T17:10:14.580533Z","steps":["trace[491357370] 'agreement among raft nodes before linearized reading' (duration: 1.881508835s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.580593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:12.698865Z","time spent":"1.88172116s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T17:10:14.580327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38649312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T17:10:14.580352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:13.725951Z","time spent":"854.387398ms","remote":"127.0.0.1:44290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-23T17:10:14.580661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.881746969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-23T17:10:14.580691Z","caller":"traceutil/trace.go:171","msg":"trace[1533135802] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2019; }","duration":"1.386887938s","start":"2026-04-23T17:10:13.193763Z","end":"2026-04-23T17:10:14.580651Z","steps":["trace[1533135802] 'agreement among raft nodes before linearized reading' (duration: 1.386498181s)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:10:14.580732Z","caller":"traceutil/trace.go:171","msg":"trace[1651830002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2019; }","duration":"1.881861802s","start":"2026-04-23T17:10:12.698851Z","end":"2026-04-23T17:10:14.580713Z","steps":["trace[1651830002] 'agreement among raft nodes before linearized reading' (duration: 1.881643238s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.580774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:12.698839Z","time spent":"1.881926103s","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T17:10:14.580805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:13.193741Z","time spent":"1.387019971s","remote":"127.0.0.1:44080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-23T17:10:14.580829Z","caller":"traceutil/trace.go:171","msg":"trace[1239876235] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"702.820993ms","start":"2026-04-23T17:10:13.877267Z","end":"2026-04-23T17:10:14.580088Z","steps":["trace[1239876235] 'process raft request' (duration: 702.595469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.580944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.883461662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-23T17:10:14.580983Z","caller":"traceutil/trace.go:171","msg":"trace[976542280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2019; }","duration":"1.883499133s","start":"2026-04-23T17:10:12.697472Z","end":"2026-04-23T17:10:14.580971Z","steps":["trace[976542280] 'agreement among raft nodes before linearized reading' (duration: 1.882515094s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T17:10:14.581017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:12.697412Z","time spent":"1.883597954s","remote":"127.0.0.1:44222","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-04-23T17:10:14.58106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T17:10:13.877218Z","time spent":"703.71811ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T17:10:14.649639Z","caller":"traceutil/trace.go:171","msg":"trace[257072445] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"163.217446ms","start":"2026-04-23T17:10:14.486389Z","end":"2026-04-23T17:10:14.649607Z","steps":["trace[257072445] 'process raft request' (duration: 106.615926ms)","trace[257072445] 'compare' (duration: 56.163242ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T17:10:14.688311Z","caller":"traceutil/trace.go:171","msg":"trace[286982900] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"102.162441ms","start":"2026-04-23T17:10:14.586115Z","end":"2026-04-23T17:10:14.688278Z","steps":["trace[286982900] 'process raft request' (duration: 101.818065ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:10:14.688462Z","caller":"traceutil/trace.go:171","msg":"trace[2065860853] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"102.253133ms","start":"2026-04-23T17:10:14.586176Z","end":"2026-04-23T17:10:14.688429Z","steps":["trace[2065860853] 'process raft request' (duration: 102.008639ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:10:14.68884Z","caller":"traceutil/trace.go:171","msg":"trace[413431632] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"102.571458ms","start":"2026-04-23T17:10:14.586253Z","end":"2026-04-23T17:10:14.688825Z","steps":["trace[413431632] 'process raft request' (duration: 101.971837ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T17:10:14.689248Z","caller":"traceutil/trace.go:171","msg":"trace[1943949887] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"102.481978ms","start":"2026-04-23T17:10:14.586745Z","end":"2026-04-23T17:10:14.689227Z","steps":["trace[1943949887] 'process raft request' (duration: 101.936767ms)"],"step_count":1}