{"level":"warn","ts":"2026-04-20T10:43:29.754067Z","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-20T10:43:29.754201Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-20T10:43:29.754304Z","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-20T10:43:29.754318Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-20T10:43:29.75438Z","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-20T10:43:29.755395Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-20T10:43:29.755524Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-20T10:43:29.760702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.888189ms"} {"level":"info","ts":"2026-04-20T10:43:29.776131Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-20T10:43:29.776298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:43:29.776333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-20T10:43:29.776358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:43:29.776371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-20T10:43:29.776438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-20T10:43:29.78766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:43:29.78922Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:43:29.790652Z","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-20T10:43:29.793136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:43:29.793397Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:43:29.793636Z","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-20T10:43:29.793748Z","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-20T10:43:29.793761Z","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-20T10:43:29.794986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-20T10:43:29.797317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-20T10:43:29.799266Z","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-20T10:43:29.799556Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:43:29.799602Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:43:29.799659Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-20T10:43:29.79969Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-20T10:43:30.077243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:43:30.077341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:43:30.077403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-20T10:43:30.077623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:43:30.077651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-20T10:43:30.077705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-20T10:43:30.077743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-20T10:43:30.078888Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:43:30.079835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:43:30.079836Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:43:30.079869Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:43:30.080156Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:43:30.08018Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:43:30.080374Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:43:30.080576Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:43:30.080656Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:43:30.081964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-20T10:43:30.084411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:43:52.421275Z","caller":"traceutil/trace.go:171","msg":"trace[509657370] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"105.645822ms","start":"2026-04-20T10:43:52.315576Z","end":"2026-04-20T10:43:52.421222Z","steps":["trace[509657370] 'process raft request' (duration: 105.420188ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:56.972378Z","caller":"traceutil/trace.go:171","msg":"trace[831686886] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"317.845191ms","start":"2026-04-20T10:43:56.654493Z","end":"2026-04-20T10:43:56.972338Z","steps":["trace[831686886] 'process raft request' (duration: 310.920735ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:56.972338Z","caller":"traceutil/trace.go:171","msg":"trace[1403759842] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"309.867395ms","start":"2026-04-20T10:43:56.66244Z","end":"2026-04-20T10:43:56.972308Z","steps":["trace[1403759842] 'process raft request' (duration: 309.62252ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:43:56.973023Z","caller":"traceutil/trace.go:171","msg":"trace[826921533] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"275.994828ms","start":"2026-04-20T10:43:56.696972Z","end":"2026-04-20T10:43:56.972967Z","steps":["trace[826921533] 'process raft request' (duration: 275.804544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:56.973126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:56.654481Z","time spent":"317.998085ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:43:56.973281Z","caller":"traceutil/trace.go:171","msg":"trace[1077524361] transaction","detail":"{read_only:false; number_of_response:1; response_revision:506; }","duration":"248.441455ms","start":"2026-04-20T10:43:56.724821Z","end":"2026-04-20T10:43:56.973263Z","steps":["trace[1077524361] 'process raft request' (duration: 248.128269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:43:56.973462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:43:56.662422Z","time spent":"310.088989ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:43:56.973805Z","caller":"traceutil/trace.go:171","msg":"trace[742476] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"207.630703ms","start":"2026-04-20T10:43:56.766157Z","end":"2026-04-20T10:43:56.973788Z","steps":["trace[742476] 'process raft request' (duration: 207.047632ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:44:02.36589Z","caller":"traceutil/trace.go:171","msg":"trace[965156935] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"202.329266ms","start":"2026-04-20T10:44:02.163532Z","end":"2026-04-20T10:44:02.365862Z","steps":["trace[965156935] 'process raft request' (duration: 173.139755ms)","trace[965156935] 'compare' (duration: 29.075959ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:44:02.402723Z","caller":"traceutil/trace.go:171","msg":"trace[1503613126] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"160.869089ms","start":"2026-04-20T10:44:02.241827Z","end":"2026-04-20T10:44:02.402696Z","steps":["trace[1503613126] 'read index received' (duration: 94.884702ms)","trace[1503613126] 'applied index is now lower than readState.Index' (duration: 65.983437ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:44:02.402833Z","caller":"traceutil/trace.go:171","msg":"trace[1874188069] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"190.481948ms","start":"2026-04-20T10:44:02.212321Z","end":"2026-04-20T10:44:02.402803Z","steps":["trace[1874188069] 'process raft request' (duration: 190.234173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:44:02.402972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.126076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T10:44:02.403042Z","caller":"traceutil/trace.go:171","msg":"trace[1695035901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"161.213727ms","start":"2026-04-20T10:44:02.241815Z","end":"2026-04-20T10:44:02.403028Z","steps":["trace[1695035901] 'agreement among raft nodes before linearized reading' (duration: 160.981852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:44:02.933687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.694919ms","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-20T10:44:02.933769Z","caller":"traceutil/trace.go:171","msg":"trace[1531204529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"408.828661ms","start":"2026-04-20T10:44:02.524922Z","end":"2026-04-20T10:44:02.933751Z","steps":["trace[1531204529] 'range keys from in-memory index tree' (duration: 408.497485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:44:02.933809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:44:02.5249Z","time spent":"408.900433ms","remote":"127.0.0.1:45312","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-20T10:44:02.933832Z","caller":"traceutil/trace.go:171","msg":"trace[1274111164] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"127.129291ms","start":"2026-04-20T10:44:02.806675Z","end":"2026-04-20T10:44:02.933804Z","steps":["trace[1274111164] 'process raft request' (duration: 116.820752ms)","trace[1274111164] 'compare' (duration: 10.149366ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:44:17.637579Z","caller":"traceutil/trace.go:171","msg":"trace[1893464009] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"137.362503ms","start":"2026-04-20T10:44:17.500196Z","end":"2026-04-20T10:44:17.637558Z","steps":["trace[1893464009] 'process raft request' (duration: 137.19837ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:44:52.801331Z","caller":"traceutil/trace.go:171","msg":"trace[1446713429] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"143.278136ms","start":"2026-04-20T10:44:52.658025Z","end":"2026-04-20T10:44:52.801304Z","steps":["trace[1446713429] 'process raft request' (duration: 143.100694ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:40.566013Z","caller":"traceutil/trace.go:171","msg":"trace[1071663060] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"154.114697ms","start":"2026-04-20T10:45:40.411873Z","end":"2026-04-20T10:45:40.565987Z","steps":["trace[1071663060] 'read index received' (duration: 153.835973ms)","trace[1071663060] 'applied index is now lower than readState.Index' (duration: 277.884µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:45:40.56624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.33929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:45:40.566395Z","caller":"traceutil/trace.go:171","msg":"trace[650737538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"154.514873ms","start":"2026-04-20T10:45:40.41185Z","end":"2026-04-20T10:45:40.566365Z","steps":["trace[650737538] 'agreement among raft nodes before linearized reading' (duration: 154.27893ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:40.566098Z","caller":"traceutil/trace.go:171","msg":"trace[1671804814] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"160.43722ms","start":"2026-04-20T10:45:40.405608Z","end":"2026-04-20T10:45:40.566045Z","steps":["trace[1671804814] 'process raft request' (duration: 160.162606ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:42.847915Z","caller":"traceutil/trace.go:171","msg":"trace[1685667590] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"197.474604ms","start":"2026-04-20T10:45:42.650416Z","end":"2026-04-20T10:45:42.847891Z","steps":["trace[1685667590] 'process raft request' (duration: 196.442738ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:42.865731Z","caller":"traceutil/trace.go:171","msg":"trace[1121694158] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1145; }","duration":"105.646748ms","start":"2026-04-20T10:45:42.760063Z","end":"2026-04-20T10:45:42.865709Z","steps":["trace[1121694158] 'read index received' (duration: 86.812382ms)","trace[1121694158] 'applied index is now lower than readState.Index' (duration: 18.833446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:45:42.86584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.759409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:45:42.865879Z","caller":"traceutil/trace.go:171","msg":"trace[167176621] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1106; }","duration":"105.843491ms","start":"2026-04-20T10:45:42.760013Z","end":"2026-04-20T10:45:42.865856Z","steps":["trace[167176621] 'agreement among raft nodes before linearized reading' (duration: 105.77818ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:42.865915Z","caller":"traceutil/trace.go:171","msg":"trace[2092235766] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"209.313516ms","start":"2026-04-20T10:45:42.656555Z","end":"2026-04-20T10:45:42.865868Z","steps":["trace[2092235766] 'process raft request' (duration: 209.039742ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:46.966425Z","caller":"traceutil/trace.go:171","msg":"trace[561714594] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"100.137326ms","start":"2026-04-20T10:45:46.866264Z","end":"2026-04-20T10:45:46.966402Z","steps":["trace[561714594] 'process raft request' (duration: 99.971444ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:47.264412Z","caller":"traceutil/trace.go:171","msg":"trace[1707326823] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"383.224793ms","start":"2026-04-20T10:45:46.881173Z","end":"2026-04-20T10:45:47.264398Z","steps":["trace[1707326823] 'process raft request' (duration: 373.658303ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:47.264401Z","caller":"traceutil/trace.go:171","msg":"trace[1492080402] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"353.259824ms","start":"2026-04-20T10:45:46.911108Z","end":"2026-04-20T10:45:47.264368Z","steps":["trace[1492080402] 'read index received' (duration: 55.062266ms)","trace[1492080402] 'applied index is now lower than readState.Index' (duration: 298.195978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:45:47.264541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:45:46.881158Z","time spent":"383.307914ms","remote":"127.0.0.1:45312","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-20T10:45:47.264778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.65718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:45:47.265065Z","caller":"traceutil/trace.go:171","msg":"trace[1130738974] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1120; }","duration":"353.993895ms","start":"2026-04-20T10:45:46.911054Z","end":"2026-04-20T10:45:47.265047Z","steps":["trace[1130738974] 'agreement among raft nodes before linearized reading' (duration: 353.523538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:47.265162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:45:46.911036Z","time spent":"354.104366ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:45:47.266149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.144041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T10:45:47.266204Z","caller":"traceutil/trace.go:171","msg":"trace[820535657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1120; }","duration":"219.223362ms","start":"2026-04-20T10:45:47.046966Z","end":"2026-04-20T10:45:47.266189Z","steps":["trace[820535657] 'agreement among raft nodes before linearized reading' (duration: 219.06791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:48.837078Z","caller":"traceutil/trace.go:171","msg":"trace[1211405824] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"132.308397ms","start":"2026-04-20T10:45:48.704746Z","end":"2026-04-20T10:45:48.837054Z","steps":["trace[1211405824] 'read index received' (duration: 132.014643ms)","trace[1211405824] 'applied index is now lower than readState.Index' (duration: 292.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:45:48.837142Z","caller":"traceutil/trace.go:171","msg":"trace[637277561] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"182.073047ms","start":"2026-04-20T10:45:48.655035Z","end":"2026-04-20T10:45:48.837108Z","steps":["trace[637277561] 'process raft request' (duration: 181.856493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:48.837204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.431561ms","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-20T10:45:48.837237Z","caller":"traceutil/trace.go:171","msg":"trace[868938542] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1125; }","duration":"132.527052ms","start":"2026-04-20T10:45:48.704701Z","end":"2026-04-20T10:45:48.837228Z","steps":["trace[868938542] 'agreement among raft nodes before linearized reading' (duration: 132.449671ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:53.564456Z","caller":"traceutil/trace.go:171","msg":"trace[900633285] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"111.807698ms","start":"2026-04-20T10:45:53.452613Z","end":"2026-04-20T10:45:53.56442Z","steps":["trace[900633285] 'process raft request' (duration: 39.844303ms)","trace[900633285] 'compare' (duration: 71.786352ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:45:55.457578Z","caller":"traceutil/trace.go:171","msg":"trace[1663600366] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"150.451034ms","start":"2026-04-20T10:45:55.3071Z","end":"2026-04-20T10:45:55.457551Z","steps":["trace[1663600366] 'process raft request' (duration: 150.291832ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:55.458177Z","caller":"traceutil/trace.go:171","msg":"trace[789676987] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"137.641746ms","start":"2026-04-20T10:45:55.320508Z","end":"2026-04-20T10:45:55.45815Z","steps":["trace[789676987] 'process raft request' (duration: 137.474303ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:59.389209Z","caller":"traceutil/trace.go:171","msg":"trace[249769042] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"455.37229ms","start":"2026-04-20T10:45:58.93382Z","end":"2026-04-20T10:45:59.389192Z","steps":["trace[249769042] 'read index received' (duration: 454.704891ms)","trace[249769042] 'applied index is now lower than readState.Index' (duration: 666.789µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:45:59.389269Z","caller":"traceutil/trace.go:171","msg":"trace[265954532] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"490.573396ms","start":"2026-04-20T10:45:58.898669Z","end":"2026-04-20T10:45:59.389242Z","steps":["trace[265954532] 'process raft request' (duration: 490.400463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:59.389353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.508312ms","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":"warn","ts":"2026-04-20T10:45:59.389408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:45:58.898649Z","time spent":"490.679957ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:45:59.389416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.433721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T10:45:59.389431Z","caller":"traceutil/trace.go:171","msg":"trace[1313051612] 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:1158; }","duration":"455.637123ms","start":"2026-04-20T10:45:58.933774Z","end":"2026-04-20T10:45:59.389412Z","steps":["trace[1313051612] 'agreement among raft nodes before linearized reading' (duration: 455.492061ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:45:59.389464Z","caller":"traceutil/trace.go:171","msg":"trace[1549959437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1158; }","duration":"257.517313ms","start":"2026-04-20T10:45:59.131936Z","end":"2026-04-20T10:45:59.389453Z","steps":["trace[1549959437] 'agreement among raft nodes before linearized reading' (duration: 257.374561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:45:59.389473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:45:58.933756Z","time spent":"455.709715ms","remote":"127.0.0.1:36448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:45:59.57495Z","caller":"traceutil/trace.go:171","msg":"trace[1996180207] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"182.29732ms","start":"2026-04-20T10:45:59.39262Z","end":"2026-04-20T10:45:59.574917Z","steps":["trace[1996180207] 'process raft request' (duration: 131.813951ms)","trace[1996180207] 'compare' (duration: 50.356877ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:03.569668Z","caller":"traceutil/trace.go:171","msg":"trace[794831357] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"152.261004ms","start":"2026-04-20T10:46:03.417381Z","end":"2026-04-20T10:46:03.569642Z","steps":["trace[794831357] 'read index received' (duration: 64.68467ms)","trace[794831357] 'applied index is now lower than readState.Index' (duration: 87.575264ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:03.569743Z","caller":"traceutil/trace.go:171","msg":"trace[774865280] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"152.474037ms","start":"2026-04-20T10:46:03.417222Z","end":"2026-04-20T10:46:03.569696Z","steps":["trace[774865280] 'process raft request' (duration: 64.908283ms)","trace[774865280] 'compare' (duration: 87.34174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:03.569921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.513157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:46:03.569938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.694033ms","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-20T10:46:03.569986Z","caller":"traceutil/trace.go:171","msg":"trace[665807610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"152.596489ms","start":"2026-04-20T10:46:03.417376Z","end":"2026-04-20T10:46:03.569972Z","steps":["trace[665807610] 'agreement among raft nodes before linearized reading' (duration: 152.408116ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:03.569995Z","caller":"traceutil/trace.go:171","msg":"trace[1523716530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"124.805404ms","start":"2026-04-20T10:46:03.445175Z","end":"2026-04-20T10:46:03.569981Z","steps":["trace[1523716530] 'agreement among raft nodes before linearized reading' (duration: 124.610491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:04.53523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.706092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:04.535313Z","caller":"traceutil/trace.go:171","msg":"trace[501499270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"124.837883ms","start":"2026-04-20T10:46:04.410458Z","end":"2026-04-20T10:46:04.535296Z","steps":["trace[501499270] 'range keys from in-memory index tree' (duration: 124.640821ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:09.295103Z","caller":"traceutil/trace.go:171","msg":"trace[252847029] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"205.938272ms","start":"2026-04-20T10:46:09.08914Z","end":"2026-04-20T10:46:09.295078Z","steps":["trace[252847029] 'process raft request' (duration: 205.775659ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:15.90237Z","caller":"traceutil/trace.go:171","msg":"trace[1169043426] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"123.033258ms","start":"2026-04-20T10:46:15.779313Z","end":"2026-04-20T10:46:15.902347Z","steps":["trace[1169043426] 'process raft request' (duration: 122.857355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:16.039274Z","caller":"traceutil/trace.go:171","msg":"trace[364630292] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"133.369568ms","start":"2026-04-20T10:46:15.905866Z","end":"2026-04-20T10:46:16.039236Z","steps":["trace[364630292] 'process raft request' (duration: 38.280106ms)","trace[364630292] 'compare' (duration: 94.95056ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:33.607558Z","caller":"traceutil/trace.go:171","msg":"trace[924683044] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"140.451992ms","start":"2026-04-20T10:46:33.467082Z","end":"2026-04-20T10:46:33.607534Z","steps":["trace[924683044] 'process raft request' (duration: 140.278504ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:34.141311Z","caller":"traceutil/trace.go:171","msg":"trace[2035402147] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"132.782747ms","start":"2026-04-20T10:46:34.008493Z","end":"2026-04-20T10:46:34.141276Z","steps":["trace[2035402147] 'process raft request' (duration: 132.549586ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:34.160152Z","caller":"traceutil/trace.go:171","msg":"trace[1429571847] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"112.032686ms","start":"2026-04-20T10:46:34.048082Z","end":"2026-04-20T10:46:34.160115Z","steps":["trace[1429571847] 'process raft request' (duration: 111.416037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:37.120492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.886911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:37.120559Z","caller":"traceutil/trace.go:171","msg":"trace[1799642358] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1282; }","duration":"242.988686ms","start":"2026-04-20T10:46:36.877556Z","end":"2026-04-20T10:46:37.120545Z","steps":["trace[1799642358] 'count revisions from in-memory index tree' (duration: 242.838129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:37.120785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.97592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T10:46:37.120828Z","caller":"traceutil/trace.go:171","msg":"trace[1399553158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"394.061204ms","start":"2026-04-20T10:46:36.726756Z","end":"2026-04-20T10:46:37.120818Z","steps":["trace[1399553158] 'range keys from in-memory index tree' (duration: 393.821333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:37.120852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:36.72669Z","time spent":"394.155488ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:46:41.310683Z","caller":"traceutil/trace.go:171","msg":"trace[867451897] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"169.895523ms","start":"2026-04-20T10:46:41.140749Z","end":"2026-04-20T10:46:41.310645Z","steps":["trace[867451897] 'process raft request' (duration: 169.685208ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:46.925397Z","caller":"traceutil/trace.go:171","msg":"trace[1898844705] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"107.227535ms","start":"2026-04-20T10:46:46.818144Z","end":"2026-04-20T10:46:46.925371Z","steps":["trace[1898844705] 'process raft request' (duration: 107.083652ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:48.601107Z","caller":"traceutil/trace.go:171","msg":"trace[800759731] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"370.11512ms","start":"2026-04-20T10:46:48.23096Z","end":"2026-04-20T10:46:48.601075Z","steps":["trace[800759731] 'process raft request' (duration: 369.942127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:48.601287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:48.230934Z","time spent":"370.268894ms","remote":"127.0.0.1:45312","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-20T10:46:48.618829Z","caller":"traceutil/trace.go:171","msg":"trace[83883504] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"344.106777ms","start":"2026-04-20T10:46:48.274692Z","end":"2026-04-20T10:46:48.618798Z","steps":["trace[83883504] 'process raft request' (duration: 343.949213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:48.619016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:48.274669Z","time spent":"344.26652ms","remote":"127.0.0.1:45312","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-20T10:46:48.646879Z","caller":"traceutil/trace.go:171","msg":"trace[1410794990] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1371; }","duration":"150.930788ms","start":"2026-04-20T10:46:48.495914Z","end":"2026-04-20T10:46:48.646844Z","steps":["trace[1410794990] 'read index received' (duration: 105.043009ms)","trace[1410794990] 'applied index is now lower than readState.Index' (duration: 45.886249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:48.647167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.040637ms","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-20T10:46:48.647183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.231654ms","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-20T10:46:48.647225Z","caller":"traceutil/trace.go:171","msg":"trace[1462047930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"103.155359ms","start":"2026-04-20T10:46:48.544054Z","end":"2026-04-20T10:46:48.64721Z","steps":["trace[1462047930] 'agreement among raft nodes before linearized reading' (duration: 102.991066ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:48.64727Z","caller":"traceutil/trace.go:171","msg":"trace[1699265955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"151.348866ms","start":"2026-04-20T10:46:48.495902Z","end":"2026-04-20T10:46:48.647251Z","steps":["trace[1699265955] 'agreement among raft nodes before linearized reading' (duration: 151.082971ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:49.682689Z","caller":"traceutil/trace.go:171","msg":"trace[2106867379] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"203.766014ms","start":"2026-04-20T10:46:49.47889Z","end":"2026-04-20T10:46:49.682656Z","steps":["trace[2106867379] 'process raft request' (duration: 203.60216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:46:52.951513Z","caller":"traceutil/trace.go:171","msg":"trace[1686576653] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"171.769362ms","start":"2026-04-20T10:46:52.779713Z","end":"2026-04-20T10:46:52.951482Z","steps":["trace[1686576653] 'process raft request' (duration: 108.758829ms)","trace[1686576653] 'compare' (duration: 62.825159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:53.704707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.440049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:46:53.704841Z","caller":"traceutil/trace.go:171","msg":"trace[1673558910] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"294.452697ms","start":"2026-04-20T10:46:53.410367Z","end":"2026-04-20T10:46:53.70482Z","steps":["trace[1673558910] 'read index received' (duration: 164.62506ms)","trace[1673558910] 'applied index is now lower than readState.Index' (duration: 129.826027ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:53.704956Z","caller":"traceutil/trace.go:171","msg":"trace[226551436] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"305.283358ms","start":"2026-04-20T10:46:53.399655Z","end":"2026-04-20T10:46:53.704938Z","steps":["trace[226551436] 'process raft request' (duration: 175.326818ms)","trace[226551436] 'compare' (duration: 129.222345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:46:53.705058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.660842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:46:53.705145Z","caller":"traceutil/trace.go:171","msg":"trace[475582335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"294.763174ms","start":"2026-04-20T10:46:53.41036Z","end":"2026-04-20T10:46:53.705124Z","steps":["trace[475582335] 'agreement among raft nodes before linearized reading' (duration: 294.622051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:46:53.705158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:46:53.399624Z","time spent":"305.486233ms","remote":"127.0.0.1:45128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:46:53.877045Z","caller":"traceutil/trace.go:171","msg":"trace[1067577677] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"147.983125ms","start":"2026-04-20T10:46:53.729028Z","end":"2026-04-20T10:46:53.877011Z","steps":["trace[1067577677] 'process raft request' (duration: 108.884551ms)","trace[1067577677] 'compare' (duration: 38.953651ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:54.7864Z","caller":"traceutil/trace.go:171","msg":"trace[1472536828] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"126.627399ms","start":"2026-04-20T10:46:54.659735Z","end":"2026-04-20T10:46:54.786363Z","steps":["trace[1472536828] 'process raft request' (duration: 98.475169ms)","trace[1472536828] 'compare' (duration: 27.816793ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:46:56.903767Z","caller":"traceutil/trace.go:171","msg":"trace[398745026] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"111.808604ms","start":"2026-04-20T10:46:56.791932Z","end":"2026-04-20T10:46:56.903741Z","steps":["trace[398745026] 'process raft request' (duration: 25.940803ms)","trace[398745026] 'compare' (duration: 85.715537ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:11.167071Z","caller":"traceutil/trace.go:171","msg":"trace[610396325] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1452; }","duration":"111.321734ms","start":"2026-04-20T10:47:11.055719Z","end":"2026-04-20T10:47:11.167041Z","steps":["trace[610396325] 'read index received' (duration: 21.54649ms)","trace[610396325] 'applied index is now lower than readState.Index' (duration: 89.774214ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:11.167436Z","caller":"traceutil/trace.go:171","msg":"trace[627459329] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"123.546724ms","start":"2026-04-20T10:47:11.043832Z","end":"2026-04-20T10:47:11.167378Z","steps":["trace[627459329] 'process raft request' (duration: 106.240995ms)","trace[627459329] 'compare' (duration: 16.855479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:11.167736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.756373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T10:47:11.167853Z","caller":"traceutil/trace.go:171","msg":"trace[326097674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1395; }","duration":"112.152471ms","start":"2026-04-20T10:47:11.05568Z","end":"2026-04-20T10:47:11.167832Z","steps":["trace[326097674] 'agreement among raft nodes before linearized reading' (duration: 111.563839ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:13.319984Z","caller":"traceutil/trace.go:171","msg":"trace[473889774] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"148.920605ms","start":"2026-04-20T10:47:13.171041Z","end":"2026-04-20T10:47:13.319962Z","steps":["trace[473889774] 'read index received' (duration: 148.714851ms)","trace[473889774] 'applied index is now lower than readState.Index' (duration: 204.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:13.320332Z","caller":"traceutil/trace.go:171","msg":"trace[2017146582] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"203.080059ms","start":"2026-04-20T10:47:13.117238Z","end":"2026-04-20T10:47:13.320318Z","steps":["trace[2017146582] 'process raft request' (duration: 202.586489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:13.320619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.560718ms","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-20T10:47:13.320658Z","caller":"traceutil/trace.go:171","msg":"trace[1247939628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1400; }","duration":"149.65297ms","start":"2026-04-20T10:47:13.170996Z","end":"2026-04-20T10:47:13.320649Z","steps":["trace[1247939628] 'agreement among raft nodes before linearized reading' (duration: 149.494257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:15.40593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.217481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:15.406177Z","caller":"traceutil/trace.go:171","msg":"trace[845247115] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"410.044753ms","start":"2026-04-20T10:47:14.99608Z","end":"2026-04-20T10:47:15.406125Z","steps":["trace[845247115] 'process raft request' (duration: 154.475404ms)","trace[845247115] 'compare' (duration: 254.898675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:15.406488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:14.99603Z","time spent":"410.249807ms","remote":"127.0.0.1:45312","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-20T10:47:15.533699Z","caller":"traceutil/trace.go:171","msg":"trace[2061027177] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"121.458649ms","start":"2026-04-20T10:47:15.412214Z","end":"2026-04-20T10:47:15.533672Z","steps":["trace[2061027177] 'process raft request' (duration: 96.970438ms)","trace[2061027177] 'compare' (duration: 24.296237ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:15.534685Z","caller":"traceutil/trace.go:171","msg":"trace[2097173240] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"120.832916ms","start":"2026-04-20T10:47:15.413681Z","end":"2026-04-20T10:47:15.534514Z","steps":["trace[2097173240] 'process raft request' (duration: 119.946996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:16.178804Z","caller":"traceutil/trace.go:171","msg":"trace[1993045549] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"124.706809ms","start":"2026-04-20T10:47:16.054068Z","end":"2026-04-20T10:47:16.178775Z","steps":["trace[1993045549] 'process raft request' (duration: 124.502955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:17.730477Z","caller":"traceutil/trace.go:171","msg":"trace[100471116] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"234.208983ms","start":"2026-04-20T10:47:17.496247Z","end":"2026-04-20T10:47:17.730456Z","steps":["trace[100471116] 'process raft request' (duration: 234.039268ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:17.730625Z","caller":"traceutil/trace.go:171","msg":"trace[875265182] linearizableReadLoop","detail":"{readStateIndex:1473; appliedIndex:1473; }","duration":"218.644981ms","start":"2026-04-20T10:47:17.511952Z","end":"2026-04-20T10:47:17.730597Z","steps":["trace[875265182] 'read index received' (duration: 218.633411ms)","trace[875265182] 'applied index is now lower than readState.Index' (duration: 9.6µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:17.730792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.807415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:17.730856Z","caller":"traceutil/trace.go:171","msg":"trace[1143266112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1413; }","duration":"218.925097ms","start":"2026-04-20T10:47:17.511915Z","end":"2026-04-20T10:47:17.73084Z","steps":["trace[1143266112] 'agreement among raft nodes before linearized reading' (duration: 218.795694ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:17.761451Z","caller":"traceutil/trace.go:171","msg":"trace[1395022255] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"106.04309ms","start":"2026-04-20T10:47:17.65539Z","end":"2026-04-20T10:47:17.761433Z","steps":["trace[1395022255] 'process raft request' (duration: 105.908078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:17.761566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.162828ms","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-20T10:47:17.761621Z","caller":"traceutil/trace.go:171","msg":"trace[346055675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1414; }","duration":"223.288741ms","start":"2026-04-20T10:47:17.538316Z","end":"2026-04-20T10:47:17.761605Z","steps":["trace[346055675] 'agreement among raft nodes before linearized reading' (duration: 223.094787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:17.761683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.457314ms","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-20T10:47:17.761784Z","caller":"traceutil/trace.go:171","msg":"trace[1566227770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1414; }","duration":"222.595996ms","start":"2026-04-20T10:47:17.539163Z","end":"2026-04-20T10:47:17.761759Z","steps":["trace[1566227770] 'agreement among raft nodes before linearized reading' (duration: 222.28429ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:19.241251Z","caller":"traceutil/trace.go:171","msg":"trace[1233008388] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"199.354331ms","start":"2026-04-20T10:47:19.041879Z","end":"2026-04-20T10:47:19.241234Z","steps":["trace[1233008388] 'process raft request' (duration: 199.203467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:19.546357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.820214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:19.546452Z","caller":"traceutil/trace.go:171","msg":"trace[1486683856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1418; }","duration":"134.986028ms","start":"2026-04-20T10:47:19.411447Z","end":"2026-04-20T10:47:19.546433Z","steps":["trace[1486683856] 'range keys from in-memory index tree' (duration: 134.756123ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:19.96817Z","caller":"traceutil/trace.go:171","msg":"trace[698704732] linearizableReadLoop","detail":"{readStateIndex:1481; appliedIndex:1480; }","duration":"233.588439ms","start":"2026-04-20T10:47:19.734566Z","end":"2026-04-20T10:47:19.968154Z","steps":["trace[698704732] 'read index received' (duration: 233.446706ms)","trace[698704732] 'applied index is now lower than readState.Index' (duration: 141.213µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:19.968345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.895287ms","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-20T10:47:19.968376Z","caller":"traceutil/trace.go:171","msg":"trace[176449793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1420; }","duration":"165.990919ms","start":"2026-04-20T10:47:19.802377Z","end":"2026-04-20T10:47:19.968368Z","steps":["trace[176449793] 'agreement among raft nodes before linearized reading' (duration: 165.857956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:19.96839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.855624ms","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-20T10:47:19.968425Z","caller":"traceutil/trace.go:171","msg":"trace[931029461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1420; }","duration":"126.914925ms","start":"2026-04-20T10:47:19.841501Z","end":"2026-04-20T10:47:19.968416Z","steps":["trace[931029461] 'agreement among raft nodes before linearized reading' (duration: 126.833603ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:19.968342Z","caller":"traceutil/trace.go:171","msg":"trace[1608550380] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"234.170792ms","start":"2026-04-20T10:47:19.734139Z","end":"2026-04-20T10:47:19.96831Z","steps":["trace[1608550380] 'process raft request' (duration: 233.916506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:19.968634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.004858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T10:47:19.968746Z","caller":"traceutil/trace.go:171","msg":"trace[768978304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"234.198203ms","start":"2026-04-20T10:47:19.734521Z","end":"2026-04-20T10:47:19.96872Z","steps":["trace[768978304] 'agreement among raft nodes before linearized reading' (duration: 233.723432ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:20.16598Z","caller":"traceutil/trace.go:171","msg":"trace[1665846903] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"193.72936ms","start":"2026-04-20T10:47:19.97222Z","end":"2026-04-20T10:47:20.16595Z","steps":["trace[1665846903] 'process raft request' (duration: 136.898768ms)","trace[1665846903] 'compare' (duration: 56.560036ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:20.166017Z","caller":"traceutil/trace.go:171","msg":"trace[1416345381] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"193.641349ms","start":"2026-04-20T10:47:19.972353Z","end":"2026-04-20T10:47:20.165994Z","steps":["trace[1416345381] 'process raft request' (duration: 193.488326ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:20.165979Z","caller":"traceutil/trace.go:171","msg":"trace[2053451553] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"193.160458ms","start":"2026-04-20T10:47:19.972797Z","end":"2026-04-20T10:47:20.165957Z","steps":["trace[2053451553] 'process raft request' (duration: 193.104947ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:20.598571Z","caller":"traceutil/trace.go:171","msg":"trace[1786003155] linearizableReadLoop","detail":"{readStateIndex:1485; appliedIndex:1484; }","duration":"278.538088ms","start":"2026-04-20T10:47:20.320004Z","end":"2026-04-20T10:47:20.598542Z","steps":["trace[1786003155] 'read index received' (duration: 278.320453ms)","trace[1786003155] 'applied index is now lower than readState.Index' (duration: 216.795µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:20.598617Z","caller":"traceutil/trace.go:171","msg":"trace[1755150995] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"392.073569ms","start":"2026-04-20T10:47:20.206518Z","end":"2026-04-20T10:47:20.598591Z","steps":["trace[1755150995] 'process raft request' (duration: 391.746092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:20.598706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.692681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:20.598753Z","caller":"traceutil/trace.go:171","msg":"trace[1580672929] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1424; }","duration":"278.779813ms","start":"2026-04-20T10:47:20.319958Z","end":"2026-04-20T10:47:20.598737Z","steps":["trace[1580672929] 'agreement among raft nodes before linearized reading' (duration: 278.691711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:20.598745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:20.206489Z","time spent":"392.180711ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:21.039404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.366843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:21.039471Z","caller":"traceutil/trace.go:171","msg":"trace[1245439415] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1424; }","duration":"261.491395ms","start":"2026-04-20T10:47:20.777965Z","end":"2026-04-20T10:47:21.039456Z","steps":["trace[1245439415] 'count revisions from in-memory index tree' (duration: 261.268442ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:21.390409Z","caller":"traceutil/trace.go:171","msg":"trace[77228168] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"140.417744ms","start":"2026-04-20T10:47:21.249968Z","end":"2026-04-20T10:47:21.390386Z","steps":["trace[77228168] 'process raft request' (duration: 140.25182ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:21.806946Z","caller":"traceutil/trace.go:171","msg":"trace[1090259392] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"256.365037ms","start":"2026-04-20T10:47:21.550515Z","end":"2026-04-20T10:47:21.80688Z","steps":["trace[1090259392] 'process raft request' (duration: 254.950456ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.333788Z","caller":"traceutil/trace.go:171","msg":"trace[516983132] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"161.312989ms","start":"2026-04-20T10:47:22.172446Z","end":"2026-04-20T10:47:22.333759Z","steps":["trace[516983132] 'process raft request' (duration: 161.142556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.866128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.860562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:22.866288Z","caller":"traceutil/trace.go:171","msg":"trace[857748985] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1488; }","duration":"455.476371ms","start":"2026-04-20T10:47:22.410799Z","end":"2026-04-20T10:47:22.866276Z","steps":["trace[857748985] 'read index received' (duration: 125.333042ms)","trace[857748985] 'applied index is now lower than readState.Index' (duration: 330.142759ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:22.866372Z","caller":"traceutil/trace.go:171","msg":"trace[132555914] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"692.489723ms","start":"2026-04-20T10:47:22.173875Z","end":"2026-04-20T10:47:22.866365Z","steps":["trace[132555914] 'process raft request' (duration: 692.331279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:22.866367Z","caller":"traceutil/trace.go:171","msg":"trace[2142623653] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"692.981693ms","start":"2026-04-20T10:47:22.173329Z","end":"2026-04-20T10:47:22.86631Z","steps":["trace[2142623653] 'process raft request' (duration: 362.870406ms)","trace[2142623653] 'compare' (duration: 329.666368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:22.866414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.173857Z","time spent":"692.535204ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:22.866502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.173309Z","time spent":"693.119567ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:22.866742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.404377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T10:47:22.866825Z","caller":"traceutil/trace.go:171","msg":"trace[1505542598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1429; }","duration":"264.530099ms","start":"2026-04-20T10:47:22.602272Z","end":"2026-04-20T10:47:22.866802Z","steps":["trace[1505542598] 'agreement among raft nodes before linearized reading' (duration: 264.292044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.86664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.841228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:22.86722Z","caller":"traceutil/trace.go:171","msg":"trace[1232613237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1429; }","duration":"456.458291ms","start":"2026-04-20T10:47:22.410747Z","end":"2026-04-20T10:47:22.867206Z","steps":["trace[1232613237] 'agreement among raft nodes before linearized reading' (duration: 455.804588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:22.867305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:22.410728Z","time spent":"456.521382ms","remote":"127.0.0.1:45100","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-20T10:47:23.077032Z","caller":"traceutil/trace.go:171","msg":"trace[89254709] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"206.945812ms","start":"2026-04-20T10:47:22.870065Z","end":"2026-04-20T10:47:23.077011Z","steps":["trace[89254709] 'process raft request' (duration: 206.813269ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:37.5695Z","caller":"traceutil/trace.go:171","msg":"trace[1952962430] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"105.490529ms","start":"2026-04-20T10:47:37.463975Z","end":"2026-04-20T10:47:37.569465Z","steps":["trace[1952962430] 'process raft request' (duration: 105.338556ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:38.311119Z","caller":"traceutil/trace.go:171","msg":"trace[1545878805] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1541; }","duration":"195.121321ms","start":"2026-04-20T10:47:38.11597Z","end":"2026-04-20T10:47:38.311092Z","steps":["trace[1545878805] 'read index received' (duration: 195.010239ms)","trace[1545878805] 'applied index is now lower than readState.Index' (duration: 110.152µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:38.311193Z","caller":"traceutil/trace.go:171","msg":"trace[865522600] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"452.155192ms","start":"2026-04-20T10:47:37.859001Z","end":"2026-04-20T10:47:38.311156Z","steps":["trace[865522600] 'process raft request' (duration: 451.930327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:38.311281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.286964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:38.31132Z","caller":"traceutil/trace.go:171","msg":"trace[835389200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1478; }","duration":"195.405147ms","start":"2026-04-20T10:47:38.115901Z","end":"2026-04-20T10:47:38.311306Z","steps":["trace[835389200] 'agreement among raft nodes before linearized reading' (duration: 195.312725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:38.311634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:37.858963Z","time spent":"452.306275ms","remote":"127.0.0.1:45312","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-20T10:47:38.570794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.173543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:38.570932Z","caller":"traceutil/trace.go:171","msg":"trace[1745536256] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"259.718197ms","start":"2026-04-20T10:47:38.311193Z","end":"2026-04-20T10:47:38.570911Z","steps":["trace[1745536256] 'read index received' (duration: 18.248838ms)","trace[1745536256] 'applied index is now lower than readState.Index' (duration: 241.467889ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:47:38.570946Z","caller":"traceutil/trace.go:171","msg":"trace[294924230] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"386.562937ms","start":"2026-04-20T10:47:38.184359Z","end":"2026-04-20T10:47:38.570922Z","steps":["trace[294924230] 'process raft request' (duration: 145.149379ms)","trace[294924230] 'compare' (duration: 241.052601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:38.571045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:38.184333Z","time spent":"386.66265ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:47:38.571238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.544239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T10:47:38.57131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.291155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:38.571329Z","caller":"traceutil/trace.go:171","msg":"trace[559855395] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1479; }","duration":"194.683902ms","start":"2026-04-20T10:47:38.376618Z","end":"2026-04-20T10:47:38.571302Z","steps":["trace[559855395] 'agreement among raft nodes before linearized reading' (duration: 194.505109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:38.571381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.493015ms","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-20T10:47:38.571066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.957399ms","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-20T10:47:38.571438Z","caller":"traceutil/trace.go:171","msg":"trace[1861405328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1479; }","duration":"159.573937ms","start":"2026-04-20T10:47:38.411851Z","end":"2026-04-20T10:47:38.571425Z","steps":["trace[1861405328] 'agreement among raft nodes before linearized reading' (duration: 159.410823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:38.571462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.22116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:38.571487Z","caller":"traceutil/trace.go:171","msg":"trace[527329498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1479; }","duration":"160.284582ms","start":"2026-04-20T10:47:38.411195Z","end":"2026-04-20T10:47:38.57148Z","steps":["trace[527329498] 'agreement among raft nodes before linearized reading' (duration: 160.243411ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:38.571348Z","caller":"traceutil/trace.go:171","msg":"trace[200770668] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1479; }","duration":"133.353536ms","start":"2026-04-20T10:47:38.437984Z","end":"2026-04-20T10:47:38.571337Z","steps":["trace[200770668] 'agreement among raft nodes before linearized reading' (duration: 133.284274ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:38.571515Z","caller":"traceutil/trace.go:171","msg":"trace[998637518] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1479; }","duration":"393.48825ms","start":"2026-04-20T10:47:38.177947Z","end":"2026-04-20T10:47:38.571436Z","steps":["trace[998637518] 'agreement among raft nodes before linearized reading' (duration: 393.070001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:38.571749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:38.177927Z","time spent":"393.676723ms","remote":"127.0.0.1:45224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:39.058277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.657267ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T10:47:39.058402Z","caller":"traceutil/trace.go:171","msg":"trace[1328935697] linearizableReadLoop","detail":"{readStateIndex:1545; appliedIndex:1544; }","duration":"426.228526ms","start":"2026-04-20T10:47:38.632156Z","end":"2026-04-20T10:47:39.058385Z","steps":["trace[1328935697] 'read index received' (duration: 12.410676ms)","trace[1328935697] 'applied index is now lower than readState.Index' (duration: 413.81638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:39.058523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.356619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:39.05855Z","caller":"traceutil/trace.go:171","msg":"trace[438819382] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1480; }","duration":"426.4276ms","start":"2026-04-20T10:47:38.632114Z","end":"2026-04-20T10:47:39.058542Z","steps":["trace[438819382] 'agreement among raft nodes before linearized reading' (duration: 426.364729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:39.058583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:38.632099Z","time spent":"426.476132ms","remote":"127.0.0.1:45336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:39.058728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.547584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T10:47:39.058848Z","caller":"traceutil/trace.go:171","msg":"trace[202202564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1480; }","duration":"125.719818ms","start":"2026-04-20T10:47:38.933104Z","end":"2026-04-20T10:47:39.058824Z","steps":["trace[202202564] 'agreement among raft nodes before linearized reading' (duration: 125.448122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:39.05888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.707244ms","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-20T10:47:39.059009Z","caller":"traceutil/trace.go:171","msg":"trace[1065513222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1480; }","duration":"120.878688ms","start":"2026-04-20T10:47:38.938097Z","end":"2026-04-20T10:47:39.058976Z","steps":["trace[1065513222] 'agreement among raft nodes before linearized reading' (duration: 120.48679ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:39.485249Z","caller":"traceutil/trace.go:171","msg":"trace[1460810436] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"421.063839ms","start":"2026-04-20T10:47:39.064158Z","end":"2026-04-20T10:47:39.485222Z","steps":["trace[1460810436] 'process raft request' (duration: 420.893706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:39.485415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:39.064136Z","time spent":"421.194023ms","remote":"127.0.0.1:45312","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-20T10:47:40.063979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.640498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:40.064057Z","caller":"traceutil/trace.go:171","msg":"trace[1950150851] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1545; }","duration":"917.934536ms","start":"2026-04-20T10:47:39.146111Z","end":"2026-04-20T10:47:40.064046Z","steps":["trace[1950150851] 'read index received' (duration: 338.903332ms)","trace[1950150851] 'applied index is now lower than readState.Index' (duration: 579.030164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:40.064223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.776227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:47:40.064258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.12252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T10:47:40.064344Z","caller":"traceutil/trace.go:171","msg":"trace[1417523377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1482; }","duration":"470.90065ms","start":"2026-04-20T10:47:39.593419Z","end":"2026-04-20T10:47:40.06432Z","steps":["trace[1417523377] 'agreement among raft nodes before linearized reading' (duration: 470.711276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:40.064354Z","caller":"traceutil/trace.go:171","msg":"trace[1233593660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1482; }","duration":"918.273363ms","start":"2026-04-20T10:47:39.146059Z","end":"2026-04-20T10:47:40.064332Z","steps":["trace[1233593660] 'agreement among raft nodes before linearized reading' (duration: 918.036328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:40.06444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:39.593343Z","time spent":"471.048132ms","remote":"127.0.0.1:45100","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-20T10:47:40.064258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.77383ms","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-20T10:47:40.064458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:39.146035Z","time spent":"918.364575ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:47:40.064506Z","caller":"traceutil/trace.go:171","msg":"trace[1357991028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1482; }","duration":"491.058995ms","start":"2026-04-20T10:47:39.573428Z","end":"2026-04-20T10:47:40.064487Z","steps":["trace[1357991028] 'agreement among raft nodes before linearized reading' (duration: 490.704188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:40.064569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:39.5734Z","time spent":"491.153677ms","remote":"127.0.0.1:45312","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-20T10:47:40.064619Z","caller":"traceutil/trace.go:171","msg":"trace[1927658253] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"999.155104ms","start":"2026-04-20T10:47:39.064898Z","end":"2026-04-20T10:47:40.064053Z","steps":["trace[1927658253] 'process raft request' (duration: 718.370743ms)","trace[1927658253] 'compare' (duration: 280.21359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:40.064994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:39.064865Z","time spent":"1.000007122s","remote":"127.0.0.1:45312","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-20T10:47:40.587774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.932083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:47:40.587919Z","caller":"traceutil/trace.go:171","msg":"trace[294263651] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"269.943717ms","start":"2026-04-20T10:47:40.317964Z","end":"2026-04-20T10:47:40.587908Z","steps":["trace[294263651] 'process raft request' (duration: 269.917336ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:40.58795Z","caller":"traceutil/trace.go:171","msg":"trace[1474965890] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"517.935282ms","start":"2026-04-20T10:47:40.069991Z","end":"2026-04-20T10:47:40.587926Z","steps":["trace[1474965890] 'process raft request' (duration: 517.863661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:40.588063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:40.06997Z","time spent":"518.036294ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:40.587933Z","caller":"traceutil/trace.go:171","msg":"trace[1958950253] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"518.474613ms","start":"2026-04-20T10:47:40.069429Z","end":"2026-04-20T10:47:40.587903Z","steps":["trace[1958950253] 'process raft request' (duration: 286.354137ms)","trace[1958950253] 'compare' (duration: 231.83807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:40.58829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:40.069403Z","time spent":"518.800949ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:47:40.641909Z","caller":"traceutil/trace.go:171","msg":"trace[191304221] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1547; }","duration":"230.86519ms","start":"2026-04-20T10:47:40.411012Z","end":"2026-04-20T10:47:40.641878Z","steps":["trace[191304221] 'read index received' (duration: 172.562575ms)","trace[191304221] 'applied index is now lower than readState.Index' (duration: 58.301435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:40.642095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.032473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:40.642073Z","caller":"traceutil/trace.go:171","msg":"trace[249078498] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"268.705462ms","start":"2026-04-20T10:47:40.373331Z","end":"2026-04-20T10:47:40.642037Z","steps":["trace[249078498] 'process raft request' (duration: 268.465637ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:40.642215Z","caller":"traceutil/trace.go:171","msg":"trace[13151858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1486; }","duration":"231.210957ms","start":"2026-04-20T10:47:40.41096Z","end":"2026-04-20T10:47:40.642171Z","steps":["trace[13151858] 'agreement among raft nodes before linearized reading' (duration: 231.046623ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:41.155067Z","caller":"traceutil/trace.go:171","msg":"trace[2045609927] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"479.048669ms","start":"2026-04-20T10:47:40.676001Z","end":"2026-04-20T10:47:41.15505Z","steps":["trace[2045609927] 'read index received' (duration: 478.890925ms)","trace[2045609927] 'applied index is now lower than readState.Index' (duration: 157.224µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:47:41.155168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.15309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:47:41.155191Z","caller":"traceutil/trace.go:171","msg":"trace[931755638] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1487; }","duration":"479.224792ms","start":"2026-04-20T10:47:40.675957Z","end":"2026-04-20T10:47:41.155182Z","steps":["trace[931755638] 'agreement among raft nodes before linearized reading' (duration: 479.160701ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:47:41.155155Z","caller":"traceutil/trace.go:171","msg":"trace[1726791725] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"507.722501ms","start":"2026-04-20T10:47:40.647391Z","end":"2026-04-20T10:47:41.155113Z","steps":["trace[1726791725] 'process raft request' (duration: 507.543717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:41.155214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:40.675942Z","time spent":"479.267343ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:47:41.155473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:47:40.647367Z","time spent":"507.881394ms","remote":"127.0.0.1:45312","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-20T10:47:41.576662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.143832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:47:41.576744Z","caller":"traceutil/trace.go:171","msg":"trace[45279942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1487; }","duration":"166.291265ms","start":"2026-04-20T10:47:41.410434Z","end":"2026-04-20T10:47:41.576725Z","steps":["trace[45279942] 'range keys from in-memory index tree' (duration: 166.050851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:47:41.576736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.883886ms","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-20T10:47:41.576821Z","caller":"traceutil/trace.go:171","msg":"trace[1750495471] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1487; }","duration":"134.03417ms","start":"2026-04-20T10:47:41.442765Z","end":"2026-04-20T10:47:41.576799Z","steps":["trace[1750495471] 'count revisions from in-memory index tree' (duration: 133.762014ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:15.911541Z","caller":"traceutil/trace.go:171","msg":"trace[1204347103] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"153.565552ms","start":"2026-04-20T10:48:15.757953Z","end":"2026-04-20T10:48:15.911519Z","steps":["trace[1204347103] 'process raft request' (duration: 153.45496ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:16.386146Z","caller":"traceutil/trace.go:171","msg":"trace[1299191525] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"150.724025ms","start":"2026-04-20T10:48:16.235397Z","end":"2026-04-20T10:48:16.386121Z","steps":["trace[1299191525] 'process raft request' (duration: 150.546721ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:22.06694Z","caller":"traceutil/trace.go:171","msg":"trace[908346450] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"129.273482ms","start":"2026-04-20T10:48:21.937643Z","end":"2026-04-20T10:48:22.066916Z","steps":["trace[908346450] 'process raft request' (duration: 129.124939ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:22.570959Z","caller":"traceutil/trace.go:171","msg":"trace[1893650555] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"154.577255ms","start":"2026-04-20T10:48:22.416362Z","end":"2026-04-20T10:48:22.570939Z","steps":["trace[1893650555] 'process raft request' (duration: 154.463063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:22.897954Z","caller":"traceutil/trace.go:171","msg":"trace[428414248] linearizableReadLoop","detail":"{readStateIndex:1696; appliedIndex:1695; }","duration":"128.609607ms","start":"2026-04-20T10:48:22.769324Z","end":"2026-04-20T10:48:22.897934Z","steps":["trace[428414248] 'read index received' (duration: 91.846418ms)","trace[428414248] 'applied index is now lower than readState.Index' (duration: 36.762699ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:22.897997Z","caller":"traceutil/trace.go:171","msg":"trace[1971837138] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"167.539862ms","start":"2026-04-20T10:48:22.730425Z","end":"2026-04-20T10:48:22.897965Z","steps":["trace[1971837138] 'process raft request' (duration: 130.939986ms)","trace[1971837138] 'compare' (duration: 36.441353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:22.898148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.18004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:48:22.898185Z","caller":"traceutil/trace.go:171","msg":"trace[1892513435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1623; }","duration":"117.263582ms","start":"2026-04-20T10:48:22.780913Z","end":"2026-04-20T10:48:22.898176Z","steps":["trace[1892513435] 'agreement among raft nodes before linearized reading' (duration: 117.13246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:22.898191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.861512ms","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-20T10:48:22.898264Z","caller":"traceutil/trace.go:171","msg":"trace[75276578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1623; }","duration":"128.972955ms","start":"2026-04-20T10:48:22.769272Z","end":"2026-04-20T10:48:22.898245Z","steps":["trace[75276578] 'agreement among raft nodes before linearized reading' (duration: 128.75486ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:28.303277Z","caller":"traceutil/trace.go:171","msg":"trace[854009389] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"203.097087ms","start":"2026-04-20T10:48:28.100149Z","end":"2026-04-20T10:48:28.303246Z","steps":["trace[854009389] 'process raft request' (duration: 202.839242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:28.605066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.488388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:28.605134Z","caller":"traceutil/trace.go:171","msg":"trace[269699216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1640; }","duration":"192.60975ms","start":"2026-04-20T10:48:28.412511Z","end":"2026-04-20T10:48:28.60512Z","steps":["trace[269699216] 'range keys from in-memory index tree' (duration: 192.415876ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:28.751414Z","caller":"traceutil/trace.go:171","msg":"trace[762283221] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"142.523594ms","start":"2026-04-20T10:48:28.608864Z","end":"2026-04-20T10:48:28.751388Z","steps":["trace[762283221] 'process raft request' (duration: 142.344401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:29.305549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.329619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T10:48:29.30588Z","caller":"traceutil/trace.go:171","msg":"trace[418877024] linearizableReadLoop","detail":"{readStateIndex:1717; appliedIndex:1715; }","duration":"323.872113ms","start":"2026-04-20T10:48:28.981983Z","end":"2026-04-20T10:48:29.305855Z","steps":["trace[418877024] 'read index received' (duration: 137.845108ms)","trace[418877024] 'applied index is now lower than readState.Index' (duration: 186.025505ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:29.305984Z","caller":"traceutil/trace.go:171","msg":"trace[1096736250] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"389.680591ms","start":"2026-04-20T10:48:28.916259Z","end":"2026-04-20T10:48:29.30594Z","steps":["trace[1096736250] 'process raft request' (duration: 389.412696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:29.30611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.117857ms","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-20T10:48:29.306176Z","caller":"traceutil/trace.go:171","msg":"trace[1184659689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1642; }","duration":"324.218939ms","start":"2026-04-20T10:48:28.981938Z","end":"2026-04-20T10:48:29.306157Z","steps":["trace[1184659689] 'agreement among raft nodes before linearized reading' (duration: 324.041655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:29.306219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:28.916235Z","time spent":"389.849844ms","remote":"127.0.0.1:45312","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-20T10:48:29.306233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:28.981914Z","time spent":"324.305861ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:48:29.306226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.989091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:48:29.306315Z","caller":"traceutil/trace.go:171","msg":"trace[2134217325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1642; }","duration":"318.127264ms","start":"2026-04-20T10:48:28.988167Z","end":"2026-04-20T10:48:29.306294Z","steps":["trace[2134217325] 'agreement among raft nodes before linearized reading' (duration: 317.881889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:29.306381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:28.988141Z","time spent":"318.231485ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T10:48:34.965894Z","caller":"traceutil/trace.go:171","msg":"trace[163886727] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"190.360196ms","start":"2026-04-20T10:48:34.775514Z","end":"2026-04-20T10:48:34.965874Z","steps":["trace[163886727] 'process raft request' (duration: 190.239263ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:35.454963Z","caller":"traceutil/trace.go:171","msg":"trace[622946068] linearizableReadLoop","detail":"{readStateIndex:1740; appliedIndex:1739; }","duration":"107.287509ms","start":"2026-04-20T10:48:35.347657Z","end":"2026-04-20T10:48:35.454945Z","steps":["trace[622946068] 'read index received' (duration: 107.141295ms)","trace[622946068] 'applied index is now lower than readState.Index' (duration: 145.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:35.455047Z","caller":"traceutil/trace.go:171","msg":"trace[1476930881] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"128.79204ms","start":"2026-04-20T10:48:35.326216Z","end":"2026-04-20T10:48:35.455008Z","steps":["trace[1476930881] 'process raft request' (duration: 128.619286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:35.455129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.334225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:48:35.455151Z","caller":"traceutil/trace.go:171","msg":"trace[2067946368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1664; }","duration":"103.396446ms","start":"2026-04-20T10:48:35.351748Z","end":"2026-04-20T10:48:35.455145Z","steps":["trace[2067946368] 'agreement among raft nodes before linearized reading' (duration: 103.293214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:35.455165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.492822ms","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-20T10:48:35.455239Z","caller":"traceutil/trace.go:171","msg":"trace[1095105363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1664; }","duration":"107.612285ms","start":"2026-04-20T10:48:35.347608Z","end":"2026-04-20T10:48:35.455221Z","steps":["trace[1095105363] 'agreement among raft nodes before linearized reading' (duration: 107.406871ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:35.623099Z","caller":"traceutil/trace.go:171","msg":"trace[186299721] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"159.293852ms","start":"2026-04-20T10:48:35.463787Z","end":"2026-04-20T10:48:35.623081Z","steps":["trace[186299721] 'process raft request' (duration: 159.271651ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:35.623156Z","caller":"traceutil/trace.go:171","msg":"trace[735521599] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"164.026373ms","start":"2026-04-20T10:48:35.459086Z","end":"2026-04-20T10:48:35.623113Z","steps":["trace[735521599] 'process raft request' (duration: 128.049835ms)","trace[735521599] 'compare' (duration: 35.791294ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:35.623172Z","caller":"traceutil/trace.go:171","msg":"trace[654931354] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"159.397634ms","start":"2026-04-20T10:48:35.463739Z","end":"2026-04-20T10:48:35.623136Z","steps":["trace[654931354] 'process raft request' (duration: 159.296002ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:39.272595Z","caller":"traceutil/trace.go:171","msg":"trace[1672076122] linearizableReadLoop","detail":"{readStateIndex:1802; appliedIndex:1801; }","duration":"129.64798ms","start":"2026-04-20T10:48:39.142919Z","end":"2026-04-20T10:48:39.272567Z","steps":["trace[1672076122] 'read index received' (duration: 129.498066ms)","trace[1672076122] 'applied index is now lower than readState.Index' (duration: 148.764µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:39.272729Z","caller":"traceutil/trace.go:171","msg":"trace[202870514] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"175.241189ms","start":"2026-04-20T10:48:39.097437Z","end":"2026-04-20T10:48:39.272679Z","steps":["trace[202870514] 'process raft request' (duration: 174.936982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:39.2728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.854904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:39.272846Z","caller":"traceutil/trace.go:171","msg":"trace[1473413601] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1724; }","duration":"129.966036ms","start":"2026-04-20T10:48:39.142863Z","end":"2026-04-20T10:48:39.27283Z","steps":["trace[1473413601] 'agreement among raft nodes before linearized reading' (duration: 129.866144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:39.581912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.704902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-20T10:48:39.582014Z","caller":"traceutil/trace.go:171","msg":"trace[2039789560] linearizableReadLoop","detail":"{readStateIndex:1803; appliedIndex:1802; }","duration":"309.303621ms","start":"2026-04-20T10:48:39.272695Z","end":"2026-04-20T10:48:39.581999Z","steps":["trace[2039789560] 'read index received' (duration: 149.445685ms)","trace[2039789560] 'applied index is now lower than readState.Index' (duration: 159.856436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:39.58208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.271765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:48:39.582068Z","caller":"traceutil/trace.go:171","msg":"trace[416691211] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1724; }","duration":"414.902773ms","start":"2026-04-20T10:48:39.167135Z","end":"2026-04-20T10:48:39.582037Z","steps":["trace[416691211] 'process raft request' (duration: 255.007677ms)","trace[416691211] 'compare' (duration: 159.644331ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:39.582138Z","caller":"traceutil/trace.go:171","msg":"trace[330787501] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1724; }","duration":"314.348496ms","start":"2026-04-20T10:48:39.267777Z","end":"2026-04-20T10:48:39.582126Z","steps":["trace[330787501] 'agreement among raft nodes before linearized reading' (duration: 314.229974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:39.582173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.267734Z","time spent":"314.430088ms","remote":"127.0.0.1:45440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:48:39.582153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.138977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:39.582159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.55358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:39.582228Z","caller":"traceutil/trace.go:171","msg":"trace[389332293] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1724; }","duration":"337.28714ms","start":"2026-04-20T10:48:39.244922Z","end":"2026-04-20T10:48:39.582209Z","steps":["trace[389332293] 'agreement among raft nodes before linearized reading' (duration: 337.122637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:39.582273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.2449Z","time spent":"337.364851ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:48:39.582273Z","caller":"traceutil/trace.go:171","msg":"trace[2048733768] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:1724; }","duration":"390.721794ms","start":"2026-04-20T10:48:39.191524Z","end":"2026-04-20T10:48:39.582246Z","steps":["trace[2048733768] 'agreement among raft nodes before linearized reading' (duration: 390.52815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:39.582203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.167109Z","time spent":"415.033667ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:39.58235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.191509Z","time spent":"390.805996ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"info","ts":"2026-04-20T10:48:39.83814Z","caller":"traceutil/trace.go:171","msg":"trace[560820037] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"372.435137ms","start":"2026-04-20T10:48:39.465679Z","end":"2026-04-20T10:48:39.838114Z","steps":["trace[560820037] 'process raft request' (duration: 372.240453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:39.838299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.465657Z","time spent":"372.558699ms","remote":"127.0.0.1:45312","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-20T10:48:40.263156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.604038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:48:40.263298Z","caller":"traceutil/trace.go:171","msg":"trace[1572265144] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"722.31541ms","start":"2026-04-20T10:48:39.540941Z","end":"2026-04-20T10:48:40.263257Z","steps":["trace[1572265144] 'process raft request' (duration: 363.536559ms)","trace[1572265144] 'compare' (duration: 358.469414ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:40.263369Z","caller":"traceutil/trace.go:171","msg":"trace[145418570] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"413.764678ms","start":"2026-04-20T10:48:39.84958Z","end":"2026-04-20T10:48:40.263344Z","steps":["trace[145418570] 'process raft request' (duration: 413.678847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.263466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.540918Z","time spent":"722.467553ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:40.263493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.849549Z","time spent":"413.883911ms","remote":"127.0.0.1:45312","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-20T10:48:40.26351Z","caller":"traceutil/trace.go:171","msg":"trace[1412514087] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1803; }","duration":"681.45484ms","start":"2026-04-20T10:48:39.582033Z","end":"2026-04-20T10:48:40.263487Z","steps":["trace[1412514087] 'read index received' (duration: 255.925624ms)","trace[1412514087] 'applied index is now lower than readState.Index' (duration: 425.526746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:40.263758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.777892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-20T10:48:40.263804Z","caller":"traceutil/trace.go:171","msg":"trace[1985964044] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1727; }","duration":"924.867044ms","start":"2026-04-20T10:48:39.338924Z","end":"2026-04-20T10:48:40.263791Z","steps":["trace[1985964044] 'agreement among raft nodes before linearized reading' (duration: 924.6745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.26384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.338907Z","time spent":"924.923695ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10266,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-20T10:48:40.263947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.522406ms","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-20T10:48:40.263996Z","caller":"traceutil/trace.go:171","msg":"trace[1313446529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1727; }","duration":"627.606578ms","start":"2026-04-20T10:48:39.636375Z","end":"2026-04-20T10:48:40.263981Z","steps":["trace[1313446529] 'agreement among raft nodes before linearized reading' (duration: 627.485155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.264015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.552466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:40.26403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.55553ms","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-20T10:48:40.264031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.424768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/openstack\" ","response":"range_response_count:1 size:368"} {"level":"warn","ts":"2026-04-20T10:48:40.26406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.004901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:40.264065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.636355Z","time spent":"627.693299ms","remote":"127.0.0.1:45312","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-20T10:48:40.264071Z","caller":"traceutil/trace.go:171","msg":"trace[485755977] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1727; }","duration":"980.670618ms","start":"2026-04-20T10:48:39.283384Z","end":"2026-04-20T10:48:40.264055Z","steps":["trace[485755977] 'agreement among raft nodes before linearized reading' (duration: 980.582066ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.264094Z","caller":"traceutil/trace.go:171","msg":"trace[1967215474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1727; }","duration":"630.680783ms","start":"2026-04-20T10:48:39.633397Z","end":"2026-04-20T10:48:40.264078Z","steps":["trace[1967215474] 'agreement among raft nodes before linearized reading' (duration: 630.504979ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.264101Z","caller":"traceutil/trace.go:171","msg":"trace[1903110944] range","detail":"{range_begin:/registry/namespaces/openstack; range_end:; response_count:1; response_revision:1727; }","duration":"680.54134ms","start":"2026-04-20T10:48:39.583546Z","end":"2026-04-20T10:48:40.264088Z","steps":["trace[1903110944] 'agreement among raft nodes before linearized reading' (duration: 680.309995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.264111Z","caller":"traceutil/trace.go:171","msg":"trace[931778521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1727; }","duration":"672.078283ms","start":"2026-04-20T10:48:39.592016Z","end":"2026-04-20T10:48:40.264094Z","steps":["trace[931778521] 'agreement among raft nodes before linearized reading' (duration: 672.007641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.264128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.633367Z","time spent":"630.755024ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T10:48:40.264126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.28335Z","time spent":"980.76319ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-04-20T10:48:40.264136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.583533Z","time spent":"680.595822ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":32,"response count":1,"response size":391,"request content":"key:\"/registry/namespaces/openstack\" "} {"level":"warn","ts":"2026-04-20T10:48:40.264145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.592002Z","time spent":"672.136614ms","remote":"127.0.0.1:45100","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-20T10:48:40.2642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.337553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:40.264318Z","caller":"traceutil/trace.go:171","msg":"trace[1369177701] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:1727; }","duration":"552.510018ms","start":"2026-04-20T10:48:39.71178Z","end":"2026-04-20T10:48:40.26429Z","steps":["trace[1369177701] 'agreement among raft nodes before linearized reading' (duration: 552.274752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.264382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.711755Z","time spent":"552.61345ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" "} {"level":"warn","ts":"2026-04-20T10:48:40.264212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.258231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:48:40.264472Z","caller":"traceutil/trace.go:171","msg":"trace[1711601693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1727; }","duration":"628.550208ms","start":"2026-04-20T10:48:39.635894Z","end":"2026-04-20T10:48:40.264444Z","steps":["trace[1711601693] 'agreement among raft nodes before linearized reading' (duration: 628.069407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.264557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:39.635883Z","time spent":"628.64827ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:48:40.686626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.89646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:48:40.686933Z","caller":"traceutil/trace.go:171","msg":"trace[1089837625] linearizableReadLoop","detail":"{readStateIndex:1809; appliedIndex:1806; }","duration":"416.108186ms","start":"2026-04-20T10:48:40.270808Z","end":"2026-04-20T10:48:40.686916Z","steps":["trace[1089837625] 'read index received' (duration: 144.824748ms)","trace[1089837625] 'applied index is now lower than readState.Index' (duration: 271.282568ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:40.68704Z","caller":"traceutil/trace.go:171","msg":"trace[2122082110] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"417.622088ms","start":"2026-04-20T10:48:40.269371Z","end":"2026-04-20T10:48:40.686993Z","steps":["trace[2122082110] 'process raft request' (duration: 417.381283ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.687066Z","caller":"traceutil/trace.go:171","msg":"trace[1066127916] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"416.218588ms","start":"2026-04-20T10:48:40.270819Z","end":"2026-04-20T10:48:40.687037Z","steps":["trace[1066127916] 'process raft request' (duration: 416.053365ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.687073Z","caller":"traceutil/trace.go:171","msg":"trace[1321059232] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"416.80302ms","start":"2026-04-20T10:48:40.270243Z","end":"2026-04-20T10:48:40.687046Z","steps":["trace[1321059232] 'process raft request' (duration: 416.592596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.687101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.272499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:40.68714Z","caller":"traceutil/trace.go:171","msg":"trace[1232462202] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd; range_end:; response_count:0; response_revision:1731; }","duration":"416.336091ms","start":"2026-04-20T10:48:40.270794Z","end":"2026-04-20T10:48:40.68713Z","steps":["trace[1232462202] 'agreement among raft nodes before linearized reading' (duration: 416.219639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.687167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:40.270789Z","time spent":"416.373452ms","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" "} {"level":"warn","ts":"2026-04-20T10:48:40.687178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:40.269344Z","time spent":"417.770312ms","remote":"127.0.0.1:45312","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-20T10:48:40.687187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:40.270804Z","time spent":"416.32466ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:40.687213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:40.270229Z","time spent":"416.905482ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:48:40.687231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.547428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:40.687178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.179716ms","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-20T10:48:40.687278Z","caller":"traceutil/trace.go:171","msg":"trace[2067938648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1731; }","duration":"275.60834ms","start":"2026-04-20T10:48:40.411657Z","end":"2026-04-20T10:48:40.687265Z","steps":["trace[2067938648] 'agreement among raft nodes before linearized reading' (duration: 275.548278ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.687282Z","caller":"traceutil/trace.go:171","msg":"trace[244865030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1731; }","duration":"329.33166ms","start":"2026-04-20T10:48:40.357939Z","end":"2026-04-20T10:48:40.687271Z","steps":["trace[244865030] 'agreement among raft nodes before linearized reading' (duration: 329.084204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.687352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:40.35792Z","time spent":"329.424143ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:48:40.687347Z","caller":"traceutil/trace.go:171","msg":"trace[1956434232] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"417.969636ms","start":"2026-04-20T10:48:40.269314Z","end":"2026-04-20T10:48:40.687283Z","steps":["trace[1956434232] 'process raft request' (duration: 146.32485ms)","trace[1956434232] 'compare' (duration: 270.729076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:40.68718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.677123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-20T10:48:40.68757Z","caller":"traceutil/trace.go:171","msg":"trace[1379518822] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1731; }","duration":"211.092782ms","start":"2026-04-20T10:48:40.476462Z","end":"2026-04-20T10:48:40.687555Z","steps":["trace[1379518822] 'agreement among raft nodes before linearized reading' (duration: 210.636041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.687611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:40.269299Z","time spent":"418.17015ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:48:40.952772Z","caller":"traceutil/trace.go:171","msg":"trace[826028886] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"258.904508ms","start":"2026-04-20T10:48:40.693846Z","end":"2026-04-20T10:48:40.952751Z","steps":["trace[826028886] 'process raft request' (duration: 258.761675ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:40.965402Z","caller":"traceutil/trace.go:171","msg":"trace[188032730] linearizableReadLoop","detail":"{readStateIndex:1812; appliedIndex:1810; }","duration":"270.074664ms","start":"2026-04-20T10:48:40.695313Z","end":"2026-04-20T10:48:40.965388Z","steps":["trace[188032730] 'read index received' (duration: 257.310805ms)","trace[188032730] 'applied index is now lower than readState.Index' (duration: 12.763099ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:40.965481Z","caller":"traceutil/trace.go:171","msg":"trace[1583509577] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"271.580765ms","start":"2026-04-20T10:48:40.693894Z","end":"2026-04-20T10:48:40.965475Z","steps":["trace[1583509577] 'process raft request' (duration: 271.405971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.96567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.312468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-20T10:48:40.965721Z","caller":"traceutil/trace.go:171","msg":"trace[164035973] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1733; }","duration":"269.381609ms","start":"2026-04-20T10:48:40.696327Z","end":"2026-04-20T10:48:40.965709Z","steps":["trace[164035973] 'agreement among raft nodes before linearized reading' (duration: 269.182365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:40.965921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.592985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:40.96596Z","caller":"traceutil/trace.go:171","msg":"trace[1632090522] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-system; range_end:; response_count:0; response_revision:1733; }","duration":"270.644336ms","start":"2026-04-20T10:48:40.695301Z","end":"2026-04-20T10:48:40.965946Z","steps":["trace[1632090522] 'agreement among raft nodes before linearized reading' (duration: 270.590125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.227685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.763172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:48:41.227844Z","caller":"traceutil/trace.go:171","msg":"trace[1157842526] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"243.551864ms","start":"2026-04-20T10:48:40.984257Z","end":"2026-04-20T10:48:41.227809Z","steps":["trace[1157842526] 'process raft request' (duration: 111.609478ms)","trace[1157842526] 'compare' (duration: 131.655ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:41.227891Z","caller":"traceutil/trace.go:171","msg":"trace[1408602875] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1734; }","duration":"232.552574ms","start":"2026-04-20T10:48:40.995323Z","end":"2026-04-20T10:48:41.227876Z","steps":["trace[1408602875] 'process raft request' (duration: 232.518283ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:41.227936Z","caller":"traceutil/trace.go:171","msg":"trace[2026711816] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1734; }","duration":"235.068827ms","start":"2026-04-20T10:48:40.992835Z","end":"2026-04-20T10:48:41.227904Z","steps":["trace[2026711816] 'process raft request' (duration: 234.959793ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:41.227935Z","caller":"traceutil/trace.go:171","msg":"trace[1383408604] linearizableReadLoop","detail":"{readStateIndex:1813; appliedIndex:1812; }","duration":"243.575505ms","start":"2026-04-20T10:48:40.984319Z","end":"2026-04-20T10:48:41.227894Z","steps":["trace[1383408604] 'read index received' (duration: 111.539796ms)","trace[1383408604] 'applied index is now lower than readState.Index' (duration: 132.031608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:41.228216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.860981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:41.228251Z","caller":"traceutil/trace.go:171","msg":"trace[375831503] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa; range_end:; response_count:0; response_revision:1734; }","duration":"243.968704ms","start":"2026-04-20T10:48:40.984273Z","end":"2026-04-20T10:48:41.228242Z","steps":["trace[375831503] 'agreement among raft nodes before linearized reading' (duration: 243.819761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.228238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.35944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:41.228256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.09387ms","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-20T10:48:41.228275Z","caller":"traceutil/trace.go:171","msg":"trace[1313497178] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:1734; }","duration":"137.445432ms","start":"2026-04-20T10:48:41.090821Z","end":"2026-04-20T10:48:41.228266Z","steps":["trace[1313497178] 'agreement among raft nodes before linearized reading' (duration: 137.36228ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:41.228293Z","caller":"traceutil/trace.go:171","msg":"trace[2020880057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1734; }","duration":"238.161812ms","start":"2026-04-20T10:48:40.990121Z","end":"2026-04-20T10:48:41.228283Z","steps":["trace[2020880057] 'agreement among raft nodes before linearized reading' (duration: 238.047129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.228347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.517083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:41.228407Z","caller":"traceutil/trace.go:171","msg":"trace[405183512] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:1734; }","duration":"137.607916ms","start":"2026-04-20T10:48:41.090776Z","end":"2026-04-20T10:48:41.228384Z","steps":["trace[405183512] 'agreement among raft nodes before linearized reading' (duration: 137.506454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.228362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.047554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:41.228482Z","caller":"traceutil/trace.go:171","msg":"trace[1093990575] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:1734; }","duration":"137.201027ms","start":"2026-04-20T10:48:41.091255Z","end":"2026-04-20T10:48:41.228456Z","steps":["trace[1093990575] 'agreement among raft nodes before linearized reading' (duration: 136.994273ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:41.417715Z","caller":"traceutil/trace.go:171","msg":"trace[11267033] linearizableReadLoop","detail":"{readStateIndex:1816; appliedIndex:1815; }","duration":"183.90344ms","start":"2026-04-20T10:48:41.233786Z","end":"2026-04-20T10:48:41.41769Z","steps":["trace[11267033] 'read index received' (duration: 183.714616ms)","trace[11267033] 'applied index is now lower than readState.Index' (duration: 187.694µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:41.417813Z","caller":"traceutil/trace.go:171","msg":"trace[1651160859] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"185.731898ms","start":"2026-04-20T10:48:41.232064Z","end":"2026-04-20T10:48:41.417796Z","steps":["trace[1651160859] 'process raft request' (duration: 185.391891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.417918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.118964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-rbd-plugin-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:41.417968Z","caller":"traceutil/trace.go:171","msg":"trace[614987809] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-csi-rbd-plugin-sa; range_end:; response_count:0; response_revision:1735; }","duration":"184.220526ms","start":"2026-04-20T10:48:41.233733Z","end":"2026-04-20T10:48:41.417953Z","steps":["trace[614987809] 'agreement among raft nodes before linearized reading' (duration: 184.087364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.716436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.036237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:48:41.716618Z","caller":"traceutil/trace.go:171","msg":"trace[565642251] linearizableReadLoop","detail":"{readStateIndex:1818; appliedIndex:1816; }","duration":"298.810289ms","start":"2026-04-20T10:48:41.41779Z","end":"2026-04-20T10:48:41.716601Z","steps":["trace[565642251] 'read index received' (duration: 140.600639ms)","trace[565642251] 'applied index is now lower than readState.Index' (duration: 158.20857ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:41.716632Z","caller":"traceutil/trace.go:171","msg":"trace[818684078] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"482.070715ms","start":"2026-04-20T10:48:41.234531Z","end":"2026-04-20T10:48:41.716602Z","steps":["trace[818684078] 'process raft request' (duration: 323.781264ms)","trace[818684078] 'compare' (duration: 157.891993ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:41.716629Z","caller":"traceutil/trace.go:171","msg":"trace[1057859128] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"385.085105ms","start":"2026-04-20T10:48:41.331521Z","end":"2026-04-20T10:48:41.716606Z","steps":["trace[1057859128] 'process raft request' (duration: 385.010724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.716753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.234519Z","time spent":"482.170637ms","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":115430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:48:41.716762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.903858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:41.716753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.331499Z","time spent":"385.198117ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:48:41.71679Z","caller":"traceutil/trace.go:171","msg":"trace[1527050290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1737; }","duration":"305.952339ms","start":"2026-04-20T10:48:41.410827Z","end":"2026-04-20T10:48:41.716779Z","steps":["trace[1527050290] 'agreement among raft nodes before linearized reading' (duration: 305.850627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:41.71683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.410812Z","time spent":"306.01066ms","remote":"127.0.0.1:45100","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-20T10:48:42.288427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.526588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:48:42.288583Z","caller":"traceutil/trace.go:171","msg":"trace[614823483] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"581.335354ms","start":"2026-04-20T10:48:41.707217Z","end":"2026-04-20T10:48:42.288552Z","steps":["trace[614823483] 'process raft request' (duration: 191.580161ms)","trace[614823483] 'compare' (duration: 389.346975ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:42.288617Z","caller":"traceutil/trace.go:171","msg":"trace[311458108] linearizableReadLoop","detail":"{readStateIndex:1819; appliedIndex:1818; }","duration":"571.957037ms","start":"2026-04-20T10:48:41.716643Z","end":"2026-04-20T10:48:42.2886Z","steps":["trace[311458108] 'read index received' (duration: 182.173564ms)","trace[311458108] 'applied index is now lower than readState.Index' (duration: 389.721852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:42.288721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.707189Z","time spent":"581.447307ms","remote":"127.0.0.1:45312","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-20T10:48:42.288734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.736803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-rbd-provisioner-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:42.288849Z","caller":"traceutil/trace.go:171","msg":"trace[1626845617] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-csi-rbd-provisioner-sa; range_end:; response_count:0; response_revision:1738; }","duration":"867.904877ms","start":"2026-04-20T10:48:41.420911Z","end":"2026-04-20T10:48:42.288816Z","steps":["trace[1626845617] 'agreement among raft nodes before linearized reading' (duration: 867.730023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.288916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.420859Z","time spent":"868.043139ms","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-rbd-provisioner-sa\" "} {"level":"info","ts":"2026-04-20T10:48:42.351283Z","caller":"traceutil/trace.go:171","msg":"trace[1373093170] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"633.091963ms","start":"2026-04-20T10:48:41.718162Z","end":"2026-04-20T10:48:42.351254Z","steps":["trace[1373093170] 'process raft request' (duration: 632.791026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.351446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.71814Z","time spent":"633.243137ms","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1151,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:48:42.35146Z","caller":"traceutil/trace.go:171","msg":"trace[498733754] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"631.233145ms","start":"2026-04-20T10:48:41.720168Z","end":"2026-04-20T10:48:42.351401Z","steps":["trace[498733754] 'process raft request' (duration: 630.986689ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:42.35155Z","caller":"traceutil/trace.go:171","msg":"trace[144239952] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"507.627464ms","start":"2026-04-20T10:48:41.843872Z","end":"2026-04-20T10:48:42.3515Z","steps":["trace[144239952] 'process raft request' (duration: 507.355318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.351615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.16358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:42.351668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.425091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-r7gtv\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-04-20T10:48:42.351701Z","caller":"traceutil/trace.go:171","msg":"trace[42909124] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:1741; }","duration":"130.282043ms","start":"2026-04-20T10:48:42.221392Z","end":"2026-04-20T10:48:42.351674Z","steps":["trace[42909124] 'agreement among raft nodes before linearized reading' (duration: 130.12672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.351707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.843839Z","time spent":"507.783097ms","remote":"127.0.0.1:45312","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-20T10:48:42.351744Z","caller":"traceutil/trace.go:171","msg":"trace[1197719421] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-r7gtv; range_end:; response_count:1; response_revision:1741; }","duration":"250.564104ms","start":"2026-04-20T10:48:42.101162Z","end":"2026-04-20T10:48:42.351726Z","steps":["trace[1197719421] 'agreement among raft nodes before linearized reading' (duration: 250.235657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.351787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.32943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:42.351837Z","caller":"traceutil/trace.go:171","msg":"trace[120706105] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:1741; }","duration":"461.403711ms","start":"2026-04-20T10:48:41.890418Z","end":"2026-04-20T10:48:42.351822Z","steps":["trace[120706105] 'agreement among raft nodes before linearized reading' (duration: 461.308529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.351881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.890402Z","time spent":"461.470652ms","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" "} {"level":"warn","ts":"2026-04-20T10:48:42.351681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.720154Z","time spent":"631.428579ms","remote":"127.0.0.1:45166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:42.352043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.855319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:42.352086Z","caller":"traceutil/trace.go:171","msg":"trace[1363202869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1741; }","duration":"633.925201ms","start":"2026-04-20T10:48:41.718148Z","end":"2026-04-20T10:48:42.352073Z","steps":["trace[1363202869] 'agreement among raft nodes before linearized reading' (duration: 633.86328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.352114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:41.718121Z","time spent":"633.987313ms","remote":"127.0.0.1:45106","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-20T10:48:42.853427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.72532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:48:42.853524Z","caller":"traceutil/trace.go:171","msg":"trace[2011856339] linearizableReadLoop","detail":"{readStateIndex:1823; appliedIndex:1822; }","duration":"494.825104ms","start":"2026-04-20T10:48:42.358684Z","end":"2026-04-20T10:48:42.85351Z","steps":["trace[2011856339] 'read index received' (duration: 212.007642ms)","trace[2011856339] 'applied index is now lower than readState.Index' (duration: 282.816252ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:42.853567Z","caller":"traceutil/trace.go:171","msg":"trace[1606030021] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"496.123681ms","start":"2026-04-20T10:48:42.357402Z","end":"2026-04-20T10:48:42.853526Z","steps":["trace[1606030021] 'process raft request' (duration: 213.230238ms)","trace[1606030021] 'compare' (duration: 282.590827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:48:42.853711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.357379Z","time spent":"496.261454ms","remote":"127.0.0.1:45240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":499,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:42.853682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.981337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:42.853918Z","caller":"traceutil/trace.go:171","msg":"trace[1509092969] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1742; }","duration":"495.259433ms","start":"2026-04-20T10:48:42.35864Z","end":"2026-04-20T10:48:42.8539Z","steps":["trace[1509092969] 'agreement among raft nodes before linearized reading' (duration: 494.954567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.854064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.35862Z","time spent":"495.329914ms","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" "} {"level":"info","ts":"2026-04-20T10:48:42.923078Z","caller":"traceutil/trace.go:171","msg":"trace[69244340] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"563.51207ms","start":"2026-04-20T10:48:42.359552Z","end":"2026-04-20T10:48:42.923064Z","steps":["trace[69244340] 'process raft request' (duration: 563.369147ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:42.923102Z","caller":"traceutil/trace.go:171","msg":"trace[1935074473] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"559.73338ms","start":"2026-04-20T10:48:42.363335Z","end":"2026-04-20T10:48:42.923068Z","steps":["trace[1935074473] 'process raft request' (duration: 559.640788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.923201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.359537Z","time spent":"563.604651ms","remote":"127.0.0.1:45506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6297,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:42.923332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.363325Z","time spent":"559.870382ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:48:42.923029Z","caller":"traceutil/trace.go:171","msg":"trace[206530098] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"564.309516ms","start":"2026-04-20T10:48:42.358686Z","end":"2026-04-20T10:48:42.922996Z","steps":["trace[206530098] 'process raft request' (duration: 564.059441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:42.923522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.358663Z","time spent":"564.837398ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:48:43.408674Z","caller":"traceutil/trace.go:171","msg":"trace[1735756953] linearizableReadLoop","detail":"{readStateIndex:1827; appliedIndex:1823; }","duration":"555.111632ms","start":"2026-04-20T10:48:42.853548Z","end":"2026-04-20T10:48:43.40866Z","steps":["trace[1735756953] 'read index received' (duration: 69.233457ms)","trace[1735756953] 'applied index is now lower than readState.Index' (duration: 485.877615ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:43.408748Z","caller":"traceutil/trace.go:171","msg":"trace[2081728958] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"704.268051ms","start":"2026-04-20T10:48:42.704451Z","end":"2026-04-20T10:48:43.408719Z","steps":["trace[2081728958] 'process raft request' (duration: 704.111768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.408816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.680524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:48:43.408836Z","caller":"traceutil/trace.go:171","msg":"trace[1539508473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1746; }","duration":"717.745905ms","start":"2026-04-20T10:48:42.691085Z","end":"2026-04-20T10:48:43.40883Z","steps":["trace[1539508473] 'agreement among raft nodes before linearized reading' (duration: 717.651603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.408854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.691066Z","time spent":"717.783346ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:48:43.408833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.173042ms","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-20T10:48:43.408886Z","caller":"traceutil/trace.go:171","msg":"trace[2143208107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1746; }","duration":"716.263094ms","start":"2026-04-20T10:48:42.69261Z","end":"2026-04-20T10:48:43.408874Z","steps":["trace[2143208107] 'agreement among raft nodes before linearized reading' (duration: 716.118781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.408893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.291174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:43.408913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.692597Z","time spent":"716.310555ms","remote":"127.0.0.1:45312","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-20T10:48:43.408931Z","caller":"traceutil/trace.go:171","msg":"trace[1553377087] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1746; }","duration":"716.353356ms","start":"2026-04-20T10:48:42.692564Z","end":"2026-04-20T10:48:43.408917Z","steps":["trace[1553377087] 'agreement among raft nodes before linearized reading' (duration: 716.304675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.40895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.704419Z","time spent":"704.407453ms","remote":"127.0.0.1:45246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11596,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:48:43.408969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.692549Z","time spent":"716.410857ms","remote":"127.0.0.1:36342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:48:43.408988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.39616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.409005Z","caller":"traceutil/trace.go:171","msg":"trace[544890514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1746; }","duration":"997.461752ms","start":"2026-04-20T10:48:42.411538Z","end":"2026-04-20T10:48:43.409Z","steps":["trace[544890514] 'agreement among raft nodes before linearized reading' (duration: 997.432311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.409017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.411507Z","time spent":"997.506572ms","remote":"127.0.0.1:45100","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-20T10:48:43.408836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.686783ms","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-20T10:48:43.409051Z","caller":"traceutil/trace.go:171","msg":"trace[889856358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1746; }","duration":"717.945049ms","start":"2026-04-20T10:48:42.6911Z","end":"2026-04-20T10:48:43.409045Z","steps":["trace[889856358] 'agreement among raft nodes before linearized reading' (duration: 717.571821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.409072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.69108Z","time spent":"717.98638ms","remote":"127.0.0.1:45312","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-20T10:48:43.409098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048144738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-operator-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.409113Z","caller":"traceutil/trace.go:171","msg":"trace[1293948618] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-operator-config; range_end:; response_count:0; response_revision:1746; }","duration":"1.048173568s","start":"2026-04-20T10:48:42.360936Z","end":"2026-04-20T10:48:43.409109Z","steps":["trace[1293948618] 'agreement among raft nodes before linearized reading' (duration: 1.048145878s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.409126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.360928Z","time spent":"1.048195629s","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/rook-ceph/rook-ceph-operator-config\" "} {"level":"warn","ts":"2026-04-20T10:48:43.409215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049619449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.409232Z","caller":"traceutil/trace.go:171","msg":"trace[1095682292] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery-worker; range_end:; response_count:0; response_revision:1746; }","duration":"1.04964822s","start":"2026-04-20T10:48:42.359576Z","end":"2026-04-20T10:48:43.409224Z","steps":["trace[1095682292] 'agreement among raft nodes before linearized reading' (duration: 1.04962098s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.409226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049735922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:43.409246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.359569Z","time spent":"1.049672331s","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" "} {"level":"info","ts":"2026-04-20T10:48:43.409259Z","caller":"traceutil/trace.go:171","msg":"trace[1034553620] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:0; response_revision:1746; }","duration":"1.049798123s","start":"2026-04-20T10:48:42.359449Z","end":"2026-04-20T10:48:43.409247Z","steps":["trace[1034553620] 'agreement among raft nodes before linearized reading' (duration: 1.049753252s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.409288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.35942Z","time spent":"1.049861405s","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" "} {"level":"info","ts":"2026-04-20T10:48:43.703519Z","caller":"traceutil/trace.go:171","msg":"trace[285709967] linearizableReadLoop","detail":"{readStateIndex:1829; appliedIndex:1827; }","duration":"294.779994ms","start":"2026-04-20T10:48:43.40872Z","end":"2026-04-20T10:48:43.7035Z","steps":["trace[285709967] 'read index received' (duration: 294.645821ms)","trace[285709967] 'applied index is now lower than readState.Index' (duration: 133.403µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:48:43.703611Z","caller":"traceutil/trace.go:171","msg":"trace[1488415730] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"770.945955ms","start":"2026-04-20T10:48:42.93263Z","end":"2026-04-20T10:48:43.703576Z","steps":["trace[1488415730] 'process raft request' (duration: 770.805972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.703711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.541733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T10:48:43.703788Z","caller":"traceutil/trace.go:171","msg":"trace[1305526375] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:1748; }","duration":"307.665106ms","start":"2026-04-20T10:48:43.396106Z","end":"2026-04-20T10:48:43.703772Z","steps":["trace[1305526375] 'agreement among raft nodes before linearized reading' (duration: 307.493122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.703862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.396087Z","time spent":"307.762718ms","remote":"127.0.0.1:45128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-04-20T10:48:43.703862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.090053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-r7gtv\" ","response":"range_response_count:1 size:5195"} {"level":"warn","ts":"2026-04-20T10:48:43.703891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.72985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.703906Z","caller":"traceutil/trace.go:171","msg":"trace[703635612] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-r7gtv; range_end:; response_count:1; response_revision:1748; }","duration":"776.180435ms","start":"2026-04-20T10:48:42.927709Z","end":"2026-04-20T10:48:43.703889Z","steps":["trace[703635612] 'agreement among raft nodes before linearized reading' (duration: 776.002951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.703912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.291307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T10:48:43.703951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.927689Z","time spent":"776.253846ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5218,"request content":"key:\"/registry/pods/monitoring/goldpinger-r7gtv\" "} {"level":"info","ts":"2026-04-20T10:48:43.703946Z","caller":"traceutil/trace.go:171","msg":"trace[1127172629] range","detail":"{range_begin:/registry/configmaps/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1748; }","duration":"846.786111ms","start":"2026-04-20T10:48:42.857149Z","end":"2026-04-20T10:48:43.703935Z","steps":["trace[1127172629] 'agreement among raft nodes before linearized reading' (duration: 846.703829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.70399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.8571Z","time spent":"846.881343ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" "} {"level":"info","ts":"2026-04-20T10:48:43.703856Z","caller":"traceutil/trace.go:171","msg":"trace[2066404307] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"771.375214ms","start":"2026-04-20T10:48:42.932352Z","end":"2026-04-20T10:48:43.703727Z","steps":["trace[2066404307] 'process raft request' (duration: 770.964595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.704167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.93233Z","time spent":"771.756252ms","remote":"127.0.0.1:45530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T10:48:43.704364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.93261Z","time spent":"771.069218ms","remote":"127.0.0.1:45180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11763,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:48:43.703987Z","caller":"traceutil/trace.go:171","msg":"trace[405252615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1748; }","duration":"747.410829ms","start":"2026-04-20T10:48:42.956559Z","end":"2026-04-20T10:48:43.70397Z","steps":["trace[405252615] 'agreement among raft nodes before linearized reading' (duration: 747.177464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.704787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:42.956538Z","time spent":"748.232037ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:48:43.730054Z","caller":"traceutil/trace.go:171","msg":"trace[681267992] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"316.88439ms","start":"2026-04-20T10:48:43.413144Z","end":"2026-04-20T10:48:43.730029Z","steps":["trace[681267992] 'process raft request' (duration: 316.811918ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:43.730083Z","caller":"traceutil/trace.go:171","msg":"trace[2107849304] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"317.164136ms","start":"2026-04-20T10:48:43.412885Z","end":"2026-04-20T10:48:43.730049Z","steps":["trace[2107849304] 'process raft request' (duration: 316.919121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.730197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.413135Z","time spent":"316.990322ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:48:43.730242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.41287Z","time spent":"317.285709ms","remote":"127.0.0.1:45312","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-20T10:48:43.730463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.554315ms","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-20T10:48:43.730506Z","caller":"traceutil/trace.go:171","msg":"trace[892600671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1751; }","duration":"308.614946ms","start":"2026-04-20T10:48:43.421884Z","end":"2026-04-20T10:48:43.730499Z","steps":["trace[892600671] 'agreement among raft nodes before linearized reading' (duration: 308.391861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.730484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.324097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:43.730556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.370526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.730552Z","caller":"traceutil/trace.go:171","msg":"trace[224367631] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"317.217446ms","start":"2026-04-20T10:48:43.413319Z","end":"2026-04-20T10:48:43.730536Z","steps":["trace[224367631] 'process raft request' (duration: 316.673455ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:43.730577Z","caller":"traceutil/trace.go:171","msg":"trace[1071361934] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1751; }","duration":"160.427597ms","start":"2026-04-20T10:48:43.570143Z","end":"2026-04-20T10:48:43.730571Z","steps":["trace[1071361934] 'agreement among raft nodes before linearized reading' (duration: 160.396946ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:43.730567Z","caller":"traceutil/trace.go:171","msg":"trace[1110584835] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-tcp; range_end:; response_count:0; response_revision:1751; }","duration":"315.44482ms","start":"2026-04-20T10:48:43.415101Z","end":"2026-04-20T10:48:43.730546Z","steps":["trace[1110584835] 'agreement among raft nodes before linearized reading' (duration: 315.188034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.730609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.415087Z","time spent":"315.513571ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" "} {"level":"warn","ts":"2026-04-20T10:48:43.730631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.413296Z","time spent":"317.289917ms","remote":"127.0.0.1:45312","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-20T10:48:43.730793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.637297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:48:43.73078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.723458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephblockpoolradosnamespaces.ceph.rook.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.730814Z","caller":"traceutil/trace.go:171","msg":"trace[44482641] range","detail":"{range_begin:/registry/configmaps/monitoring/node-feature-discovery-master-conf; range_end:; response_count:0; response_revision:1751; }","duration":"318.673007ms","start":"2026-04-20T10:48:43.412136Z","end":"2026-04-20T10:48:43.730809Z","steps":["trace[44482641] 'agreement among raft nodes before linearized reading' (duration: 318.641736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.730828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.41213Z","time spent":"318.695307ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" "} {"level":"warn","ts":"2026-04-20T10:48:43.73083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.855323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:48:43.730833Z","caller":"traceutil/trace.go:171","msg":"trace[418477220] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/cephblockpoolradosnamespaces.ceph.rook.io; range_end:; response_count:0; response_revision:1751; }","duration":"318.797ms","start":"2026-04-20T10:48:43.412023Z","end":"2026-04-20T10:48:43.73082Z","steps":["trace[418477220] 'agreement among raft nodes before linearized reading' (duration: 318.721789ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:48:43.73086Z","caller":"traceutil/trace.go:171","msg":"trace[496881297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1751; }","duration":"320.904555ms","start":"2026-04-20T10:48:43.409947Z","end":"2026-04-20T10:48:43.730851Z","steps":["trace[496881297] 'agreement among raft nodes before linearized reading' (duration: 320.857274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:48:43.730867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.412013Z","time spent":"318.846371ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/cephblockpoolradosnamespaces.ceph.rook.io\" "} {"level":"warn","ts":"2026-04-20T10:48:43.730893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.409931Z","time spent":"320.956606ms","remote":"127.0.0.1:45106","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-20T10:48:43.730524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:48:43.421866Z","time spent":"308.654136ms","remote":"127.0.0.1:45312","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-20T10:49:42.883325Z","caller":"traceutil/trace.go:171","msg":"trace[927777728] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"330.850076ms","start":"2026-04-20T10:49:42.552444Z","end":"2026-04-20T10:49:42.883294Z","steps":["trace[927777728] 'process raft request' (duration: 330.646431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:49:42.88355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:49:42.552406Z","time spent":"331.03855ms","remote":"127.0.0.1:45312","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-20T10:49:43.702244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.86372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:49:43.702387Z","caller":"traceutil/trace.go:171","msg":"trace[1517097959] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"188.723995ms","start":"2026-04-20T10:49:43.513645Z","end":"2026-04-20T10:49:43.702369Z","steps":["trace[1517097959] 'process raft request' (duration: 52.624701ms)","trace[1517097959] 'compare' (duration: 135.579783ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:49:43.702658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.656711ms","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-20T10:49:43.702775Z","caller":"traceutil/trace.go:171","msg":"trace[705249686] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2585; }","duration":"204.391696ms","start":"2026-04-20T10:49:43.498321Z","end":"2026-04-20T10:49:43.702713Z","steps":["trace[705249686] 'count revisions from in-memory index tree' (duration: 203.571488ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:49:44.213424Z","caller":"traceutil/trace.go:171","msg":"trace[1820071782] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"118.38264ms","start":"2026-04-20T10:49:44.095004Z","end":"2026-04-20T10:49:44.213387Z","steps":["trace[1820071782] 'process raft request' (duration: 118.135255ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:53:30.455238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1648} {"level":"info","ts":"2026-04-20T10:53:30.496964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1648,"took":"41.028539ms","hash":2322211259,"current-db-size-bytes":33484800,"current-db-size":"34 MB","current-db-size-in-use-bytes":14995456,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-04-20T10:53:30.497022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2322211259,"revision":1648,"compact-revision":-1} {"level":"info","ts":"2026-04-20T10:58:30.462041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3393} {"level":"info","ts":"2026-04-20T10:58:30.507285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3393,"took":"44.328507ms","hash":1388850188,"current-db-size-bytes":33484800,"current-db-size":"34 MB","current-db-size-in-use-bytes":17752064,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T10:58:30.507359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1388850188,"revision":3393,"compact-revision":1648} {"level":"info","ts":"2026-04-20T11:02:24.999221Z","caller":"traceutil/trace.go:171","msg":"trace[774194582] linearizableReadLoop","detail":"{readStateIndex:8907; appliedIndex:8906; }","duration":"106.328393ms","start":"2026-04-20T11:02:24.892871Z","end":"2026-04-20T11:02:24.999199Z","steps":["trace[774194582] 'read index received' (duration: 52.339778ms)","trace[774194582] 'applied index is now lower than readState.Index' (duration: 53.988035ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:02:24.999385Z","caller":"traceutil/trace.go:171","msg":"trace[1889407697] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"107.044979ms","start":"2026-04-20T11:02:24.892331Z","end":"2026-04-20T11:02:24.999376Z","steps":["trace[1889407697] 'process raft request' (duration: 52.965502ms)","trace[1889407697] 'compare' (duration: 53.76894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:02:24.999728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.812222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111857"} {"level":"info","ts":"2026-04-20T11:02:24.999785Z","caller":"traceutil/trace.go:171","msg":"trace[282404758] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8577; }","duration":"105.889094ms","start":"2026-04-20T11:02:24.893882Z","end":"2026-04-20T11:02:24.999771Z","steps":["trace[282404758] 'agreement among raft nodes before linearized reading' (duration: 105.592408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:02:24.999809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.921096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-20T11:02:24.999833Z","caller":"traceutil/trace.go:171","msg":"trace[2029348729] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8577; }","duration":"106.967946ms","start":"2026-04-20T11:02:24.892859Z","end":"2026-04-20T11:02:24.999827Z","steps":["trace[2029348729] 'agreement among raft nodes before linearized reading' (duration: 106.859134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:02:26.813358Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a0.wal"} {"level":"info","ts":"2026-04-20T11:03:01.942492Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T11:03:01.949011Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T11:03:01.949121Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T11:03:16.236613Z","caller":"traceutil/trace.go:171","msg":"trace[1482677430] linearizableReadLoop","detail":"{readStateIndex:11006; appliedIndex:11005; }","duration":"112.458311ms","start":"2026-04-20T11:03:16.124141Z","end":"2026-04-20T11:03:16.236599Z","steps":["trace[1482677430] 'read index received' (duration: 12.049817ms)","trace[1482677430] 'applied index is now lower than readState.Index' (duration: 100.406814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:03:16.237051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.836579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:03:16.237087Z","caller":"traceutil/trace.go:171","msg":"trace[783960681] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10645; }","duration":"112.90351ms","start":"2026-04-20T11:03:16.124175Z","end":"2026-04-20T11:03:16.237079Z","steps":["trace[783960681] 'agreement among raft nodes before linearized reading' (duration: 112.814999ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:03:16.237456Z","caller":"traceutil/trace.go:171","msg":"trace[1666498926] transaction","detail":"{read_only:false; response_revision:10644; number_of_response:1; }","duration":"106.440863ms","start":"2026-04-20T11:03:16.130994Z","end":"2026-04-20T11:03:16.237435Z","steps":["trace[1666498926] 'process raft request' (duration: 105.240238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:03:16.237592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.955732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-04-20T11:03:16.237703Z","caller":"traceutil/trace.go:171","msg":"trace[1668806452] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10645; }","duration":"113.575274ms","start":"2026-04-20T11:03:16.124115Z","end":"2026-04-20T11:03:16.23769Z","steps":["trace[1668806452] 'agreement among raft nodes before linearized reading' (duration: 112.728996ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:03:16.23953Z","caller":"traceutil/trace.go:171","msg":"trace[1016517306] transaction","detail":"{read_only:false; response_revision:10643; number_of_response:1; }","duration":"112.702486ms","start":"2026-04-20T11:03:16.123862Z","end":"2026-04-20T11:03:16.236564Z","steps":["trace[1016517306] 'process raft request' (duration: 98.621335ms)","trace[1016517306] 'compare' (duration: 13.609541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:03:20.328282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.682205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:03:20.32836Z","caller":"traceutil/trace.go:171","msg":"trace[1624651406] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:11063; }","duration":"105.804508ms","start":"2026-04-20T11:03:20.22254Z","end":"2026-04-20T11:03:20.328344Z","steps":["trace[1624651406] 'agreement among raft nodes before linearized reading' (duration: 105.675526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:03:20.328765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.460992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/cert-manager-test/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:03:20.328798Z","caller":"traceutil/trace.go:171","msg":"trace[1609227815] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/cert-manager-test/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/cert-manager-test0; response_count:0; response_revision:11063; }","duration":"105.495283ms","start":"2026-04-20T11:03:20.223294Z","end":"2026-04-20T11:03:20.328789Z","steps":["trace[1609227815] 'agreement among raft nodes before linearized reading' (duration: 105.446802ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:03:30.469482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5981} {"level":"info","ts":"2026-04-20T11:03:30.539371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5981,"took":"67.762967ms","hash":2143026472,"current-db-size-bytes":63025152,"current-db-size":"63 MB","current-db-size-in-use-bytes":54448128,"current-db-size-in-use":"54 MB"} {"level":"info","ts":"2026-04-20T11:03:30.53944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2143026472,"revision":5981,"compact-revision":3393} {"level":"info","ts":"2026-04-20T11:08:30.476024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11277} {"level":"info","ts":"2026-04-20T11:08:30.625281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11277,"took":"146.506576ms","hash":1297649325,"current-db-size-bytes":75022336,"current-db-size":"75 MB","current-db-size-in-use-bytes":48852992,"current-db-size-in-use":"49 MB"} {"level":"info","ts":"2026-04-20T11:08:30.625338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297649325,"revision":11277,"compact-revision":5981}