{"level":"warn","ts":"2026-03-18T05:35:02.296101Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T05:35:02.296233Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-18T05:35:02.296331Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T05:35:02.296344Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-18T05:35:02.296391Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T05:35:02.297153Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-03-18T05:35:02.297242Z","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://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-18T05:35:02.30237Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.860201ms"} {"level":"info","ts":"2026-03-18T05:35:02.310556Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-03-18T05:35:02.310716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T05:35:02.310752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-03-18T05:35:02.310774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T05:35:02.310786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-03-18T05:35:02.31084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-03-18T05:35:02.318274Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T05:35:02.32017Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T05:35:02.32194Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-18T05:35:02.326816Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T05:35:02.327058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T05:35:02.327194Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T05:35:02.327342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T05:35:02.32737Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T05:35:02.328562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-03-18T05:35:02.329202Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-18T05:35:02.331671Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T05:35:02.331813Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-18T05:35:02.331885Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-18T05:35:02.332011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T05:35:02.332045Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T05:35:03.212037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T05:35:03.212104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T05:35:03.212193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-03-18T05:35:03.212224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-03-18T05:35:03.212238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-18T05:35:03.212292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-03-18T05:35:03.212307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-18T05:35:03.213542Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T05:35:03.213546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:35:03.213603Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:35:03.213846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:35:03.213931Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T05:35:03.214016Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T05:35:03.214842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:35:03.215196Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:35:03.215357Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:35:03.21704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-03-18T05:35:03.217596Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T05:35:20.635652Z","caller":"traceutil/trace.go:171","msg":"trace[405936656] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"146.689271ms","start":"2026-03-18T05:35:20.488928Z","end":"2026-03-18T05:35:20.635617Z","steps":["trace[405936656] 'process raft request' (duration: 146.422956ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:35.135156Z","caller":"traceutil/trace.go:171","msg":"trace[455040809] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"187.968288ms","start":"2026-03-18T05:35:34.94697Z","end":"2026-03-18T05:35:35.134938Z","steps":["trace[455040809] 'read index received' (duration: 187.746403ms)","trace[455040809] 'applied index is now lower than readState.Index' (duration: 220.445µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:35.135245Z","caller":"traceutil/trace.go:171","msg":"trace[428671120] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"198.230324ms","start":"2026-03-18T05:35:34.93697Z","end":"2026-03-18T05:35:35.1352Z","steps":["trace[428671120] 'process raft request' (duration: 197.744944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:35.135365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.352996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4378"} {"level":"warn","ts":"2026-03-18T05:35:35.135449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.022635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-18T05:35:35.135474Z","caller":"traceutil/trace.go:171","msg":"trace[1834039331] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:468; }","duration":"188.501721ms","start":"2026-03-18T05:35:34.946953Z","end":"2026-03-18T05:35:35.135455Z","steps":["trace[1834039331] 'agreement among raft nodes before linearized reading' (duration: 188.311047ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:35.135491Z","caller":"traceutil/trace.go:171","msg":"trace[567423609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"104.116137ms","start":"2026-03-18T05:35:35.031361Z","end":"2026-03-18T05:35:35.135478Z","steps":["trace[567423609] 'agreement among raft nodes before linearized reading' (duration: 103.940243ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:41.314528Z","caller":"traceutil/trace.go:171","msg":"trace[1769559066] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"130.052875ms","start":"2026-03-18T05:35:41.184449Z","end":"2026-03-18T05:35:41.314502Z","steps":["trace[1769559066] 'read index received' (duration: 129.819099ms)","trace[1769559066] 'applied index is now lower than readState.Index' (duration: 232.766µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:41.314804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.336361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-18T05:35:41.314841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.688783ms","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-03-18T05:35:41.314889Z","caller":"traceutil/trace.go:171","msg":"trace[1100616091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"130.479774ms","start":"2026-03-18T05:35:41.18439Z","end":"2026-03-18T05:35:41.31487Z","steps":["trace[1100616091] 'agreement among raft nodes before linearized reading' (duration: 130.227439ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:41.314918Z","caller":"traceutil/trace.go:171","msg":"trace[392926894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"109.831046ms","start":"2026-03-18T05:35:41.20507Z","end":"2026-03-18T05:35:41.314901Z","steps":["trace[392926894] 'agreement among raft nodes before linearized reading' (duration: 109.615662ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:41.314603Z","caller":"traceutil/trace.go:171","msg":"trace[709797454] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"140.561952ms","start":"2026-03-18T05:35:41.174004Z","end":"2026-03-18T05:35:41.314565Z","steps":["trace[709797454] 'process raft request' (duration: 140.352987ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:41.446554Z","caller":"traceutil/trace.go:171","msg":"trace[1216708061] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"127.042573ms","start":"2026-03-18T05:35:41.319471Z","end":"2026-03-18T05:35:41.446513Z","steps":["trace[1216708061] 'process raft request' (duration: 126.939071ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:41.44656Z","caller":"traceutil/trace.go:171","msg":"trace[1104669794] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"127.043922ms","start":"2026-03-18T05:35:41.319472Z","end":"2026-03-18T05:35:41.446516Z","steps":["trace[1104669794] 'process raft request' (duration: 98.672774ms)","trace[1104669794] 'compare' (duration: 28.085692ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:42.070034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.634972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:35:42.070332Z","caller":"traceutil/trace.go:171","msg":"trace[967155339] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"609.596859ms","start":"2026-03-18T05:35:41.460692Z","end":"2026-03-18T05:35:42.070289Z","steps":["trace[967155339] 'process raft request' (duration: 498.064338ms)","trace[967155339] 'compare' (duration: 110.383717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:42.070537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:41.460667Z","time spent":"609.762632ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:35:42.289945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.746362ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T05:35:42.290147Z","caller":"traceutil/trace.go:171","msg":"trace[2063783472] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"358.979296ms","start":"2026-03-18T05:35:41.931137Z","end":"2026-03-18T05:35:42.290116Z","steps":["trace[2063783472] 'read index received' (duration: 27.635041ms)","trace[2063783472] 'applied index is now lower than readState.Index' (duration: 331.341395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:42.290351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.195221ms","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-03-18T05:35:42.290407Z","caller":"traceutil/trace.go:171","msg":"trace[1747579347] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:512; }","duration":"359.281953ms","start":"2026-03-18T05:35:41.931101Z","end":"2026-03-18T05:35:42.290382Z","steps":["trace[1747579347] 'agreement among raft nodes before linearized reading' (duration: 359.16509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:42.290401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.851775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:35:42.290458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:41.93108Z","time spent":"359.366575ms","remote":"127.0.0.1:59400","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":"info","ts":"2026-03-18T05:35:42.290483Z","caller":"traceutil/trace.go:171","msg":"trace[1371920043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"191.958717ms","start":"2026-03-18T05:35:42.098499Z","end":"2026-03-18T05:35:42.290457Z","steps":["trace[1371920043] 'agreement among raft nodes before linearized reading' (duration: 191.786504ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:43.716809Z","caller":"traceutil/trace.go:171","msg":"trace[289404209] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"198.800599ms","start":"2026-03-18T05:35:43.517966Z","end":"2026-03-18T05:35:43.716767Z","steps":["trace[289404209] 'process raft request' (duration: 189.625039ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:43.717243Z","caller":"traceutil/trace.go:171","msg":"trace[1493675195] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"103.469584ms","start":"2026-03-18T05:35:43.613742Z","end":"2026-03-18T05:35:43.717212Z","steps":["trace[1493675195] 'read index received' (duration: 93.903646ms)","trace[1493675195] 'applied index is now lower than readState.Index' (duration: 9.564418ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:43.717375Z","caller":"traceutil/trace.go:171","msg":"trace[1976562941] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"156.564194ms","start":"2026-03-18T05:35:43.560716Z","end":"2026-03-18T05:35:43.71728Z","steps":["trace[1976562941] 'process raft request' (duration: 156.081764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:43.717475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.705068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:35:43.717534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.750089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:43.717609Z","caller":"traceutil/trace.go:171","msg":"trace[76803920] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:634; }","duration":"103.890602ms","start":"2026-03-18T05:35:43.613692Z","end":"2026-03-18T05:35:43.717582Z","steps":["trace[76803920] 'agreement among raft nodes before linearized reading' (duration: 103.714939ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:43.717587Z","caller":"traceutil/trace.go:171","msg":"trace[1557068411] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:634; }","duration":"103.869382ms","start":"2026-03-18T05:35:43.613687Z","end":"2026-03-18T05:35:43.717556Z","steps":["trace[1557068411] 'agreement among raft nodes before linearized reading' (duration: 103.582026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:43.717511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.30356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:43.717929Z","caller":"traceutil/trace.go:171","msg":"trace[245277492] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:634; }","duration":"103.747499ms","start":"2026-03-18T05:35:43.614167Z","end":"2026-03-18T05:35:43.717914Z","steps":["trace[245277492] 'agreement among raft nodes before linearized reading' (duration: 103.271579ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:04.024437Z","caller":"traceutil/trace.go:171","msg":"trace[1178630288] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"256.610716ms","start":"2026-03-18T05:36:03.767795Z","end":"2026-03-18T05:36:04.024405Z","steps":["trace[1178630288] 'read index received' (duration: 166.55631ms)","trace[1178630288] 'applied index is now lower than readState.Index' (duration: 90.052856ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:04.024493Z","caller":"traceutil/trace.go:171","msg":"trace[1863039630] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"390.728133ms","start":"2026-03-18T05:36:03.633714Z","end":"2026-03-18T05:36:04.024442Z","steps":["trace[1863039630] 'process raft request' (duration: 300.668338ms)","trace[1863039630] 'compare' (duration: 89.845491ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:04.024677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.86168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-548949fc64-q9gx2\" ","response":"range_response_count:1 size:5352"} {"level":"warn","ts":"2026-03-18T05:36:04.024719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:03.633684Z","time spent":"390.913728ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:36:04.024762Z","caller":"traceutil/trace.go:171","msg":"trace[823840216] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-548949fc64-q9gx2; range_end:; response_count:1; response_revision:894; }","duration":"257.006563ms","start":"2026-03-18T05:36:03.767731Z","end":"2026-03-18T05:36:04.024737Z","steps":["trace[823840216] 'agreement among raft nodes before linearized reading' (duration: 256.779599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:04.025074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.785081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T05:36:04.025207Z","caller":"traceutil/trace.go:171","msg":"trace[47154786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"190.044027ms","start":"2026-03-18T05:36:03.835135Z","end":"2026-03-18T05:36:04.025179Z","steps":["trace[47154786] 'agreement among raft nodes before linearized reading' (duration: 189.554846ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:24.326485Z","caller":"traceutil/trace.go:171","msg":"trace[363527227] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"110.56554ms","start":"2026-03-18T05:36:24.215879Z","end":"2026-03-18T05:36:24.326444Z","steps":["trace[363527227] 'process raft request' (duration: 106.255241ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:44.2212Z","caller":"traceutil/trace.go:171","msg":"trace[1198338688] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"122.364472ms","start":"2026-03-18T05:36:44.098808Z","end":"2026-03-18T05:36:44.221173Z","steps":["trace[1198338688] 'read index received' (duration: 104.379292ms)","trace[1198338688] 'applied index is now lower than readState.Index' (duration: 17.98397ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:44.221348Z","caller":"traceutil/trace.go:171","msg":"trace[1612868489] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"130.123251ms","start":"2026-03-18T05:36:44.091192Z","end":"2026-03-18T05:36:44.221315Z","steps":["trace[1612868489] 'process raft request' (duration: 112.000058ms)","trace[1612868489] 'compare' (duration: 17.873548ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:44.221453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.615978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:36:44.221774Z","caller":"traceutil/trace.go:171","msg":"trace[686572881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1441; }","duration":"122.946675ms","start":"2026-03-18T05:36:44.098783Z","end":"2026-03-18T05:36:44.22173Z","steps":["trace[686572881] 'agreement among raft nodes before linearized reading' (duration: 122.569797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:50.957926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.261764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10ee96d9-2532-42bf-bed1-089eabb8ebbd\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-03-18T05:36:50.958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.852584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:36:50.958027Z","caller":"traceutil/trace.go:171","msg":"trace[1144818082] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10ee96d9-2532-42bf-bed1-089eabb8ebbd; range_end:; response_count:1; response_revision:1550; }","duration":"207.415197ms","start":"2026-03-18T05:36:50.750589Z","end":"2026-03-18T05:36:50.958004Z","steps":["trace[1144818082] 'range keys from in-memory index tree' (duration: 207.044029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:50.958026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.597232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-03-18T05:36:50.958097Z","caller":"traceutil/trace.go:171","msg":"trace[1603890348] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1550; }","duration":"135.025398ms","start":"2026-03-18T05:36:50.82305Z","end":"2026-03-18T05:36:50.958075Z","steps":["trace[1603890348] 'count revisions from in-memory index tree' (duration: 134.737831ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:50.958115Z","caller":"traceutil/trace.go:171","msg":"trace[446719793] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1550; }","duration":"189.747634ms","start":"2026-03-18T05:36:50.768347Z","end":"2026-03-18T05:36:50.958095Z","steps":["trace[446719793] 'range keys from in-memory index tree' (duration: 189.411977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:50.958267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.672005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:36:50.958389Z","caller":"traceutil/trace.go:171","msg":"trace[1392829402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1550; }","duration":"220.859279ms","start":"2026-03-18T05:36:50.737501Z","end":"2026-03-18T05:36:50.95836Z","steps":["trace[1392829402] 'range keys from in-memory index tree' (duration: 220.383519ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:03.423775Z","caller":"traceutil/trace.go:171","msg":"trace[983429791] linearizableReadLoop","detail":"{readStateIndex:1716; appliedIndex:1715; }","duration":"324.979007ms","start":"2026-03-18T05:37:03.098781Z","end":"2026-03-18T05:37:03.42376Z","steps":["trace[983429791] 'read index received' (duration: 324.833293ms)","trace[983429791] 'applied index is now lower than readState.Index' (duration: 145.234µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:37:03.42388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.092289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:37:03.423897Z","caller":"traceutil/trace.go:171","msg":"trace[231777952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1650; }","duration":"325.15814ms","start":"2026-03-18T05:37:03.098734Z","end":"2026-03-18T05:37:03.423892Z","steps":["trace[231777952] 'agreement among raft nodes before linearized reading' (duration: 325.084299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:03.423914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:03.098713Z","time spent":"325.197161ms","remote":"127.0.0.1:59298","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-03-18T05:37:03.423997Z","caller":"traceutil/trace.go:171","msg":"trace[2142430964] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"385.520009ms","start":"2026-03-18T05:37:03.038456Z","end":"2026-03-18T05:37:03.423976Z","steps":["trace[2142430964] 'process raft request' (duration: 385.171562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:03.424115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:03.038435Z","time spent":"385.611212ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:37:41.844647Z","caller":"traceutil/trace.go:171","msg":"trace[571551521] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"100.660582ms","start":"2026-03-18T05:37:41.74396Z","end":"2026-03-18T05:37:41.844621Z","steps":["trace[571551521] 'process raft request' (duration: 100.474889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:42.26416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.435338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:37:42.26429Z","caller":"traceutil/trace.go:171","msg":"trace[1845123141] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"419.68856ms","start":"2026-03-18T05:37:41.844584Z","end":"2026-03-18T05:37:42.264273Z","steps":["trace[1845123141] 'read index received' (duration: 200.140159ms)","trace[1845123141] 'applied index is now lower than readState.Index' (duration: 219.546801ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:37:42.264366Z","caller":"traceutil/trace.go:171","msg":"trace[467491418] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"458.39678ms","start":"2026-03-18T05:37:41.805957Z","end":"2026-03-18T05:37:42.264354Z","steps":["trace[467491418] 'process raft request' (duration: 238.663365ms)","trace[467491418] 'compare' (duration: 219.203053ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:37:42.26443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:41.80594Z","time spent":"458.45829ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:37:42.264757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.133567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:37:42.264907Z","caller":"traceutil/trace.go:171","msg":"trace[151219399] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"490.323792ms","start":"2026-03-18T05:37:41.774547Z","end":"2026-03-18T05:37:42.264871Z","steps":["trace[151219399] 'agreement among raft nodes before linearized reading' (duration: 489.977634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:42.264961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:41.774532Z","time spent":"490.416363ms","remote":"127.0.0.1:36324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-18T05:37:42.265441Z","caller":"traceutil/trace.go:171","msg":"trace[616343991] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"414.982547ms","start":"2026-03-18T05:37:41.850434Z","end":"2026-03-18T05:37:42.265416Z","steps":["trace[616343991] 'process raft request' (duration: 414.743462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:42.265541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:41.850393Z","time spent":"415.10166ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:37:42.265725Z","caller":"traceutil/trace.go:171","msg":"trace[1104737257] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"395.584877ms","start":"2026-03-18T05:37:41.870094Z","end":"2026-03-18T05:37:42.265679Z","steps":["trace[1104737257] 'process raft request' (duration: 395.26252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:42.265987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:41.870077Z","time spent":"395.798312ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8395,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:37:42.377541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.886166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T05:37:42.377851Z","caller":"traceutil/trace.go:171","msg":"trace[1898415624] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1926; }","duration":"113.49022ms","start":"2026-03-18T05:37:42.264317Z","end":"2026-03-18T05:37:42.377807Z","steps":["trace[1898415624] 'read index received' (duration: 728.475µs)","trace[1898415624] 'applied index is now lower than readState.Index' (duration: 112.759525ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:37:42.377963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.961407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:37:42.378036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.473033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T05:37:42.378031Z","caller":"traceutil/trace.go:171","msg":"trace[1733758372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"278.11487ms","start":"2026-03-18T05:37:42.099899Z","end":"2026-03-18T05:37:42.378014Z","steps":["trace[1733758372] 'agreement among raft nodes before linearized reading' (duration: 277.965277ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:42.378074Z","caller":"traceutil/trace.go:171","msg":"trace[452419891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1854; }","duration":"424.515174ms","start":"2026-03-18T05:37:41.95355Z","end":"2026-03-18T05:37:42.378065Z","steps":["trace[452419891] 'agreement among raft nodes before linearized reading' (duration: 424.371321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:42.378116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:41.953457Z","time spent":"424.649197ms","remote":"127.0.0.1:59560","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-03-18T05:37:42.378037Z","caller":"traceutil/trace.go:171","msg":"trace[1311490900] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"102.42916ms","start":"2026-03-18T05:37:42.275583Z","end":"2026-03-18T05:37:42.378012Z","steps":["trace[1311490900] 'process raft request' (duration: 102.167305ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:42.580505Z","caller":"traceutil/trace.go:171","msg":"trace[210075784] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"230.723723ms","start":"2026-03-18T05:37:42.34974Z","end":"2026-03-18T05:37:42.580463Z","steps":["trace[210075784] 'process raft request' (duration: 186.977594ms)","trace[210075784] 'compare' (duration: 43.474654ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:37:42.580501Z","caller":"traceutil/trace.go:171","msg":"trace[1383937092] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"202.567092ms","start":"2026-03-18T05:37:42.377896Z","end":"2026-03-18T05:37:42.580463Z","steps":["trace[1383937092] 'read index received' (duration: 158.828574ms)","trace[1383937092] 'applied index is now lower than readState.Index' (duration: 43.737068ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:37:42.580736Z","caller":"traceutil/trace.go:171","msg":"trace[871853773] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"198.036814ms","start":"2026-03-18T05:37:42.382652Z","end":"2026-03-18T05:37:42.580688Z","steps":["trace[871853773] 'process raft request' (duration: 197.760328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:42.58089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.393486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:37:42.580881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.418679ms","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-03-18T05:37:42.580957Z","caller":"traceutil/trace.go:171","msg":"trace[1480099352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"201.544579ms","start":"2026-03-18T05:37:42.379391Z","end":"2026-03-18T05:37:42.580935Z","steps":["trace[1480099352] 'agreement among raft nodes before linearized reading' (duration: 201.303874ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:42.580965Z","caller":"traceutil/trace.go:171","msg":"trace[57929368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"297.568492ms","start":"2026-03-18T05:37:42.283379Z","end":"2026-03-18T05:37:42.580947Z","steps":["trace[57929368] 'agreement among raft nodes before linearized reading' (duration: 297.243295ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:04.947088Z","caller":"traceutil/trace.go:171","msg":"trace[1029503791] linearizableReadLoop","detail":"{readStateIndex:2552; appliedIndex:2551; }","duration":"277.997288ms","start":"2026-03-18T05:39:04.669069Z","end":"2026-03-18T05:39:04.947066Z","steps":["trace[1029503791] 'read index received' (duration: 277.969078ms)","trace[1029503791] 'applied index is now lower than readState.Index' (duration: 26.7µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:39:04.947362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.285735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:39:04.947431Z","caller":"traceutil/trace.go:171","msg":"trace[75550077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2453; }","duration":"278.406528ms","start":"2026-03-18T05:39:04.669016Z","end":"2026-03-18T05:39:04.947422Z","steps":["trace[75550077] 'agreement among raft nodes before linearized reading' (duration: 278.188203ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:04.947648Z","caller":"traceutil/trace.go:171","msg":"trace[1055912846] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"292.890327ms","start":"2026-03-18T05:39:04.654722Z","end":"2026-03-18T05:39:04.947613Z","steps":["trace[1055912846] 'process raft request' (duration: 292.223232ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:41:31.023034Z","caller":"traceutil/trace.go:171","msg":"trace[1664005231] linearizableReadLoop","detail":"{readStateIndex:3489; appliedIndex:3488; }","duration":"416.440465ms","start":"2026-03-18T05:41:30.606565Z","end":"2026-03-18T05:41:31.023005Z","steps":["trace[1664005231] 'read index received' (duration: 414.382314ms)","trace[1664005231] 'applied index is now lower than readState.Index' (duration: 2.056951ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:41:31.023095Z","caller":"traceutil/trace.go:171","msg":"trace[1332169485] transaction","detail":"{read_only:false; response_revision:3354; number_of_response:1; }","duration":"434.958626ms","start":"2026-03-18T05:41:30.58807Z","end":"2026-03-18T05:41:31.023029Z","steps":["trace[1332169485] 'process raft request' (duration: 432.975909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:41:31.023291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:30.588047Z","time spent":"435.13874ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:41:31.023283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.6845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-18T05:41:31.023685Z","caller":"traceutil/trace.go:171","msg":"trace[959116064] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:3354; }","duration":"417.127521ms","start":"2026-03-18T05:41:30.606538Z","end":"2026-03-18T05:41:31.023666Z","steps":["trace[959116064] 'agreement among raft nodes before linearized reading' (duration: 416.584428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:41:31.023778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:30.606527Z","time spent":"417.234834ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jnprr\" "} {"level":"info","ts":"2026-03-18T05:42:56.82875Z","caller":"traceutil/trace.go:171","msg":"trace[643963130] linearizableReadLoop","detail":"{readStateIndex:4434; appliedIndex:4433; }","duration":"169.065287ms","start":"2026-03-18T05:42:56.659661Z","end":"2026-03-18T05:42:56.828726Z","steps":["trace[643963130] 'read index received' (duration: 168.945674ms)","trace[643963130] 'applied index is now lower than readState.Index' (duration: 118.563µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:42:56.828833Z","caller":"traceutil/trace.go:171","msg":"trace[1710628681] transaction","detail":"{read_only:false; response_revision:4261; number_of_response:1; }","duration":"174.669823ms","start":"2026-03-18T05:42:56.654131Z","end":"2026-03-18T05:42:56.8288Z","steps":["trace[1710628681] 'process raft request' (duration: 174.458788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:56.829103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.418545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:4330"} {"level":"info","ts":"2026-03-18T05:42:56.82915Z","caller":"traceutil/trace.go:171","msg":"trace[178551913] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4261; }","duration":"169.503418ms","start":"2026-03-18T05:42:56.659635Z","end":"2026-03-18T05:42:56.829139Z","steps":["trace[178551913] 'agreement among raft nodes before linearized reading' (duration: 169.213021ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:56.833998Z","caller":"traceutil/trace.go:171","msg":"trace[1220463233] transaction","detail":"{read_only:false; response_revision:4262; number_of_response:1; }","duration":"141.256478ms","start":"2026-03-18T05:42:56.692721Z","end":"2026-03-18T05:42:56.833978Z","steps":["trace[1220463233] 'process raft request' (duration: 140.8947ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:56.834056Z","caller":"traceutil/trace.go:171","msg":"trace[2078131936] transaction","detail":"{read_only:false; response_revision:4263; number_of_response:1; }","duration":"140.394036ms","start":"2026-03-18T05:42:56.693641Z","end":"2026-03-18T05:42:56.834035Z","steps":["trace[2078131936] 'process raft request' (duration: 140.277664ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:42:56.834074Z","caller":"traceutil/trace.go:171","msg":"trace[1208504678] transaction","detail":"{read_only:false; response_revision:4264; number_of_response:1; }","duration":"124.177135ms","start":"2026-03-18T05:42:56.709876Z","end":"2026-03-18T05:42:56.834053Z","steps":["trace[1208504678] 'process raft request' (duration: 124.082113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:56.834266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.331978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-18T05:42:56.834296Z","caller":"traceutil/trace.go:171","msg":"trace[1530505346] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4265; }","duration":"153.40532ms","start":"2026-03-18T05:42:56.680884Z","end":"2026-03-18T05:42:56.834289Z","steps":["trace[1530505346] 'agreement among raft nodes before linearized reading' (duration: 153.318888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:42:56.834615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.072804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-18T05:42:56.834698Z","caller":"traceutil/trace.go:171","msg":"trace[1955417295] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4265; }","duration":"151.189467ms","start":"2026-03-18T05:42:56.683492Z","end":"2026-03-18T05:42:56.834681Z","steps":["trace[1955417295] 'agreement among raft nodes before linearized reading' (duration: 151.045023ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:02.260812Z","caller":"traceutil/trace.go:171","msg":"trace[1565909003] linearizableReadLoop","detail":"{readStateIndex:4609; appliedIndex:4608; }","duration":"274.944701ms","start":"2026-03-18T05:43:01.985847Z","end":"2026-03-18T05:43:02.260791Z","steps":["trace[1565909003] 'read index received' (duration: 274.743966ms)","trace[1565909003] 'applied index is now lower than readState.Index' (duration: 199.505µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:02.260874Z","caller":"traceutil/trace.go:171","msg":"trace[777939015] transaction","detail":"{read_only:false; response_revision:4429; number_of_response:1; }","duration":"279.388409ms","start":"2026-03-18T05:43:01.981455Z","end":"2026-03-18T05:43:02.260843Z","steps":["trace[777939015] 'process raft request' (duration: 279.213335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:02.261077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.211787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e5335917-96c0-4a38-86ad-4f6b84cbc82d\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-03-18T05:43:02.261111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.265962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:43:02.261123Z","caller":"traceutil/trace.go:171","msg":"trace[997399256] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e5335917-96c0-4a38-86ad-4f6b84cbc82d; range_end:; response_count:1; response_revision:4429; }","duration":"275.283409ms","start":"2026-03-18T05:43:01.985828Z","end":"2026-03-18T05:43:02.261112Z","steps":["trace[997399256] 'agreement among raft nodes before linearized reading' (duration: 275.095215ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:02.26114Z","caller":"traceutil/trace.go:171","msg":"trace[941840089] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:4429; }","duration":"266.340664ms","start":"2026-03-18T05:43:01.994791Z","end":"2026-03-18T05:43:02.261132Z","steps":["trace[941840089] 'agreement among raft nodes before linearized reading' (duration: 266.262602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:02.261286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.812882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:43:02.261359Z","caller":"traceutil/trace.go:171","msg":"trace[1419845115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4429; }","duration":"161.919055ms","start":"2026-03-18T05:43:02.09942Z","end":"2026-03-18T05:43:02.261339Z","steps":["trace[1419845115] 'agreement among raft nodes before linearized reading' (duration: 161.806322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:02.261415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.906325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:43:02.261472Z","caller":"traceutil/trace.go:171","msg":"trace[1224737906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4429; }","duration":"189.003098ms","start":"2026-03-18T05:43:02.072457Z","end":"2026-03-18T05:43:02.26146Z","steps":["trace[1224737906] 'agreement among raft nodes before linearized reading' (duration: 188.878445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:05.35714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.723716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:43:05.357414Z","caller":"traceutil/trace.go:171","msg":"trace[273110394] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4563; }","duration":"126.990163ms","start":"2026-03-18T05:43:05.230344Z","end":"2026-03-18T05:43:05.357334Z","steps":["trace[273110394] 'range keys from in-memory index tree' (duration: 126.657865ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:05.541461Z","caller":"traceutil/trace.go:171","msg":"trace[656011386] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4563; }","duration":"151.980495ms","start":"2026-03-18T05:43:05.389443Z","end":"2026-03-18T05:43:05.541424Z","steps":["trace[656011386] 'process raft request' (duration: 151.869133ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:05.605079Z","caller":"traceutil/trace.go:171","msg":"trace[1257020445] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"111.337096ms","start":"2026-03-18T05:43:05.493708Z","end":"2026-03-18T05:43:05.605045Z","steps":["trace[1257020445] 'process raft request' (duration: 111.044689ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:05.909209Z","caller":"traceutil/trace.go:171","msg":"trace[1257262112] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"176.822865ms","start":"2026-03-18T05:43:05.732359Z","end":"2026-03-18T05:43:05.909182Z","steps":["trace[1257262112] 'process raft request' (duration: 176.668521ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:06.09733Z","caller":"traceutil/trace.go:171","msg":"trace[1088159830] linearizableReadLoop","detail":"{readStateIndex:4755; appliedIndex:4751; }","duration":"241.587527ms","start":"2026-03-18T05:43:05.855714Z","end":"2026-03-18T05:43:06.097301Z","steps":["trace[1088159830] 'read index received' (duration: 53.402147ms)","trace[1088159830] 'applied index is now lower than readState.Index' (duration: 188.18407ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:06.09735Z","caller":"traceutil/trace.go:171","msg":"trace[477303339] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"244.607431ms","start":"2026-03-18T05:43:05.852709Z","end":"2026-03-18T05:43:06.097316Z","steps":["trace[477303339] 'process raft request' (duration: 244.498028ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:06.09743Z","caller":"traceutil/trace.go:171","msg":"trace[397759425] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"242.584321ms","start":"2026-03-18T05:43:05.854831Z","end":"2026-03-18T05:43:06.097415Z","steps":["trace[397759425] 'process raft request' (duration: 242.430227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:06.09768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.962027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-q77zm-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-03-18T05:43:06.097723Z","caller":"traceutil/trace.go:171","msg":"trace[362160198] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-q77zm-tls; range_end:; response_count:1; response_revision:4572; }","duration":"242.078199ms","start":"2026-03-18T05:43:05.855634Z","end":"2026-03-18T05:43:06.097712Z","steps":["trace[362160198] 'agreement among raft nodes before linearized reading' (duration: 241.928545ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:06.097333Z","caller":"traceutil/trace.go:171","msg":"trace[2049576362] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"358.355523ms","start":"2026-03-18T05:43:05.738936Z","end":"2026-03-18T05:43:06.097291Z","steps":["trace[2049576362] 'process raft request' (duration: 358.115957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:06.097838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:05.738924Z","time spent":"358.838455ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:43:06.11109Z","caller":"traceutil/trace.go:171","msg":"trace[765944724] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"196.92272ms","start":"2026-03-18T05:43:05.914144Z","end":"2026-03-18T05:43:06.111067Z","steps":["trace[765944724] 'process raft request' (duration: 196.557731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:07.792177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.964199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:43:07.792295Z","caller":"traceutil/trace.go:171","msg":"trace[1352597870] linearizableReadLoop","detail":"{readStateIndex:4788; appliedIndex:4787; }","duration":"178.159388ms","start":"2026-03-18T05:43:07.614115Z","end":"2026-03-18T05:43:07.792275Z","steps":["trace[1352597870] 'read index received' (duration: 12.958653ms)","trace[1352597870] 'applied index is now lower than readState.Index' (duration: 165.199115ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:07.792411Z","caller":"traceutil/trace.go:171","msg":"trace[1859287480] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"183.123308ms","start":"2026-03-18T05:43:07.60927Z","end":"2026-03-18T05:43:07.792393Z","steps":["trace[1859287480] 'process raft request' (duration: 17.850511ms)","trace[1859287480] 'compare' (duration: 164.842136ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:07.792679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.566347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-18T05:43:07.792728Z","caller":"traceutil/trace.go:171","msg":"trace[431134709] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:4604; }","duration":"178.637639ms","start":"2026-03-18T05:43:07.614077Z","end":"2026-03-18T05:43:07.792714Z","steps":["trace[431134709] 'agreement among raft nodes before linearized reading' (duration: 178.497736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:07.793022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.257172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777450"} {"level":"info","ts":"2026-03-18T05:43:07.7931Z","caller":"traceutil/trace.go:171","msg":"trace[564855168] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4604; }","duration":"123.385036ms","start":"2026-03-18T05:43:07.669696Z","end":"2026-03-18T05:43:07.793081Z","steps":["trace[564855168] 'agreement among raft nodes before linearized reading' (duration: 122.858123ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:07.995834Z","caller":"traceutil/trace.go:171","msg":"trace[1071057987] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"144.881213ms","start":"2026-03-18T05:43:07.850889Z","end":"2026-03-18T05:43:07.99577Z","steps":["trace[1071057987] 'process raft request' (duration: 144.508814ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:17.15388Z","caller":"traceutil/trace.go:171","msg":"trace[828591048] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"245.366681ms","start":"2026-03-18T05:43:16.90849Z","end":"2026-03-18T05:43:17.153856Z","steps":["trace[828591048] 'process raft request' (duration: 245.221017ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:24.766265Z","caller":"traceutil/trace.go:171","msg":"trace[1697105651] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"231.25497ms","start":"2026-03-18T05:43:24.534964Z","end":"2026-03-18T05:43:24.766219Z","steps":["trace[1697105651] 'process raft request' (duration: 231.017764ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:24.918873Z","caller":"traceutil/trace.go:171","msg":"trace[1469674826] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"383.636742ms","start":"2026-03-18T05:43:24.535207Z","end":"2026-03-18T05:43:24.918844Z","steps":["trace[1469674826] 'process raft request' (duration: 383.54243ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:24.918881Z","caller":"traceutil/trace.go:171","msg":"trace[1245658425] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"383.867918ms","start":"2026-03-18T05:43:24.534968Z","end":"2026-03-18T05:43:24.918836Z","steps":["trace[1245658425] 'process raft request' (duration: 353.113915ms)","trace[1245658425] 'compare' (duration: 30.504277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:24.919062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.535183Z","time spent":"383.783856ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:43:24.919155Z","caller":"traceutil/trace.go:171","msg":"trace[1084854453] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"383.874048ms","start":"2026-03-18T05:43:24.535256Z","end":"2026-03-18T05:43:24.91913Z","steps":["trace[1084854453] 'process raft request' (duration: 383.54254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:24.919322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.535229Z","time spent":"384.010262ms","remote":"127.0.0.1:59560","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-03-18T05:43:24.919325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.534949Z","time spent":"384.211616ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:43:24.919443Z","caller":"traceutil/trace.go:171","msg":"trace[1158961966] linearizableReadLoop","detail":"{readStateIndex:5151; appliedIndex:5145; }","duration":"341.955065ms","start":"2026-03-18T05:43:24.577456Z","end":"2026-03-18T05:43:24.919411Z","steps":["trace[1158961966] 'read index received' (duration: 188.480746ms)","trace[1158961966] 'applied index is now lower than readState.Index' (duration: 153.472669ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:24.91946Z","caller":"traceutil/trace.go:171","msg":"trace[606842939] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"384.132445ms","start":"2026-03-18T05:43:24.535293Z","end":"2026-03-18T05:43:24.919426Z","steps":["trace[606842939] 'process raft request' (duration: 383.749985ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:24.919488Z","caller":"traceutil/trace.go:171","msg":"trace[1638629735] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"373.57122ms","start":"2026-03-18T05:43:24.545889Z","end":"2026-03-18T05:43:24.91946Z","steps":["trace[1638629735] 'process raft request' (duration: 373.442326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:24.91959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.123741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:43:24.919654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.545872Z","time spent":"373.707814ms","remote":"127.0.0.1:59560","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-03-18T05:43:24.919695Z","caller":"traceutil/trace.go:171","msg":"trace[377901528] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4959; }","duration":"342.282324ms","start":"2026-03-18T05:43:24.577395Z","end":"2026-03-18T05:43:24.919677Z","steps":["trace[377901528] 'agreement among raft nodes before linearized reading' (duration: 342.124681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:24.919738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.577376Z","time spent":"342.353526ms","remote":"127.0.0.1:55370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:43:24.919753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.981292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82f74269-0f93-466d-a5b9-2d735970db10\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-03-18T05:43:24.919629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.535259Z","time spent":"384.284129ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:43:24.91981Z","caller":"traceutil/trace.go:171","msg":"trace[708213312] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82f74269-0f93-466d-a5b9-2d735970db10; range_end:; response_count:1; response_revision:4959; }","duration":"312.089244ms","start":"2026-03-18T05:43:24.607706Z","end":"2026-03-18T05:43:24.919795Z","steps":["trace[708213312] 'agreement among raft nodes before linearized reading' (duration: 311.876739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:24.919856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.607687Z","time spent":"312.159095ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82f74269-0f93-466d-a5b9-2d735970db10\" "} {"level":"info","ts":"2026-03-18T05:43:25.303161Z","caller":"traceutil/trace.go:171","msg":"trace[1793022389] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"204.121093ms","start":"2026-03-18T05:43:25.099013Z","end":"2026-03-18T05:43:25.303134Z","steps":["trace[1793022389] 'read index received' (duration: 203.910198ms)","trace[1793022389] 'applied index is now lower than readState.Index' (duration: 209.935µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:25.303218Z","caller":"traceutil/trace.go:171","msg":"trace[857295603] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"314.117732ms","start":"2026-03-18T05:43:24.989069Z","end":"2026-03-18T05:43:25.303187Z","steps":["trace[857295603] 'process raft request' (duration: 313.912927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:25.303357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.320969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:43:25.303425Z","caller":"traceutil/trace.go:171","msg":"trace[361445910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4960; }","duration":"204.439572ms","start":"2026-03-18T05:43:25.098972Z","end":"2026-03-18T05:43:25.303412Z","steps":["trace[361445910] 'agreement among raft nodes before linearized reading' (duration: 204.309778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:25.303544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:24.989029Z","time spent":"314.358049ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:43:27.264168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.898405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:43:27.264253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.800046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T05:43:27.264268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.645839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-18T05:43:27.264329Z","caller":"traceutil/trace.go:171","msg":"trace[1534733834] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4980; }","duration":"210.909209ms","start":"2026-03-18T05:43:27.053402Z","end":"2026-03-18T05:43:27.264311Z","steps":["trace[1534733834] 'count revisions from in-memory index tree' (duration: 210.738504ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:27.264374Z","caller":"traceutil/trace.go:171","msg":"trace[473077262] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4980; }","duration":"190.803853ms","start":"2026-03-18T05:43:27.073546Z","end":"2026-03-18T05:43:27.264349Z","steps":["trace[473077262] 'range keys from in-memory index tree' (duration: 190.433563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:27.264423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.888394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-03-18T05:43:27.26451Z","caller":"traceutil/trace.go:171","msg":"trace[927999978] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4980; }","duration":"210.017326ms","start":"2026-03-18T05:43:27.054473Z","end":"2026-03-18T05:43:27.26449Z","steps":["trace[927999978] 'range keys from in-memory index tree' (duration: 209.707279ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:27.264306Z","caller":"traceutil/trace.go:171","msg":"trace[1715570878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4980; }","duration":"165.05906ms","start":"2026-03-18T05:43:27.09921Z","end":"2026-03-18T05:43:27.264269Z","steps":["trace[1715570878] 'range keys from in-memory index tree' (duration: 164.814213ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:28.405541Z","caller":"traceutil/trace.go:171","msg":"trace[1719216975] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"103.802688ms","start":"2026-03-18T05:43:28.301694Z","end":"2026-03-18T05:43:28.405497Z","steps":["trace[1719216975] 'read index received' (duration: 64.023667ms)","trace[1719216975] 'applied index is now lower than readState.Index' (duration: 39.776391ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:28.405569Z","caller":"traceutil/trace.go:171","msg":"trace[1245906029] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"229.818755ms","start":"2026-03-18T05:43:28.175714Z","end":"2026-03-18T05:43:28.405533Z","steps":["trace[1245906029] 'process raft request' (duration: 189.994603ms)","trace[1245906029] 'compare' (duration: 39.509005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:28.405846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.160137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-03-18T05:43:28.405938Z","caller":"traceutil/trace.go:171","msg":"trace[2073551127] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4986; }","duration":"104.2975ms","start":"2026-03-18T05:43:28.301611Z","end":"2026-03-18T05:43:28.405909Z","steps":["trace[2073551127] 'agreement among raft nodes before linearized reading' (duration: 104.000193ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:29.655977Z","caller":"traceutil/trace.go:171","msg":"trace[2131018811] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"153.064539ms","start":"2026-03-18T05:43:29.502887Z","end":"2026-03-18T05:43:29.655952Z","steps":["trace[2131018811] 'process raft request' (duration: 152.865664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:33.116314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.47387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:43:33.116531Z","caller":"traceutil/trace.go:171","msg":"trace[1857001303] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"122.956592ms","start":"2026-03-18T05:43:32.993539Z","end":"2026-03-18T05:43:33.116495Z","steps":["trace[1857001303] 'process raft request' (duration: 12.221425ms)","trace[1857001303] 'compare' (duration: 110.365057ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:33.116571Z","caller":"traceutil/trace.go:171","msg":"trace[353436197] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"121.871996ms","start":"2026-03-18T05:43:32.994674Z","end":"2026-03-18T05:43:33.116546Z","steps":["trace[353436197] 'process raft request' (duration: 121.799334ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:33.11659Z","caller":"traceutil/trace.go:171","msg":"trace[1339497133] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"122.01305ms","start":"2026-03-18T05:43:32.994515Z","end":"2026-03-18T05:43:33.116528Z","steps":["trace[1339497133] 'process raft request' (duration: 121.903667ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:40.193918Z","caller":"traceutil/trace.go:171","msg":"trace[1128754131] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"133.109168ms","start":"2026-03-18T05:43:40.060777Z","end":"2026-03-18T05:43:40.193886Z","steps":["trace[1128754131] 'process raft request' (duration: 132.897863ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:44:16.266525Z","caller":"traceutil/trace.go:171","msg":"trace[1081329458] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"117.354017ms","start":"2026-03-18T05:44:16.149142Z","end":"2026-03-18T05:44:16.266496Z","steps":["trace[1081329458] 'process raft request' (duration: 116.106365ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:44:26.7535Z","caller":"traceutil/trace.go:171","msg":"trace[712606059] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"138.801515ms","start":"2026-03-18T05:44:26.614668Z","end":"2026-03-18T05:44:26.75347Z","steps":["trace[712606059] 'process raft request' (duration: 103.870348ms)","trace[712606059] 'compare' (duration: 34.738332ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:44:26.764267Z","caller":"traceutil/trace.go:171","msg":"trace[1755093584] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"122.132848ms","start":"2026-03-18T05:44:26.642106Z","end":"2026-03-18T05:44:26.764239Z","steps":["trace[1755093584] 'process raft request' (duration: 121.932503ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:45:03.23748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2744} {"level":"info","ts":"2026-03-18T05:45:03.299897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2744,"took":"60.778156ms","hash":347454370,"current-db-size-bytes":41795584,"current-db-size":"42 MB","current-db-size-in-use-bytes":31416320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-18T05:45:03.299957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":347454370,"revision":2744,"compact-revision":-1} {"level":"info","ts":"2026-03-18T05:49:33.583984Z","caller":"traceutil/trace.go:171","msg":"trace[1666047363] transaction","detail":"{read_only:false; response_revision:7626; number_of_response:1; }","duration":"191.43118ms","start":"2026-03-18T05:49:33.39253Z","end":"2026-03-18T05:49:33.583962Z","steps":["trace[1666047363] 'process raft request' (duration: 191.287037ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:33.603883Z","caller":"traceutil/trace.go:171","msg":"trace[89972605] transaction","detail":"{read_only:false; response_revision:7627; number_of_response:1; }","duration":"177.881645ms","start":"2026-03-18T05:49:33.425975Z","end":"2026-03-18T05:49:33.603857Z","steps":["trace[89972605] 'process raft request' (duration: 177.709291ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:34.941752Z","caller":"traceutil/trace.go:171","msg":"trace[1131487485] linearizableReadLoop","detail":"{readStateIndex:7913; appliedIndex:7912; }","duration":"142.123467ms","start":"2026-03-18T05:49:34.799605Z","end":"2026-03-18T05:49:34.941729Z","steps":["trace[1131487485] 'read index received' (duration: 141.966933ms)","trace[1131487485] 'applied index is now lower than readState.Index' (duration: 155.414µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:34.941985Z","caller":"traceutil/trace.go:171","msg":"trace[1237371038] transaction","detail":"{read_only:false; response_revision:7631; number_of_response:1; }","duration":"225.209389ms","start":"2026-03-18T05:49:34.716763Z","end":"2026-03-18T05:49:34.941972Z","steps":["trace[1237371038] 'process raft request' (duration: 224.820959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:34.942192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.247691ms","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-03-18T05:49:34.942312Z","caller":"traceutil/trace.go:171","msg":"trace[571287679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7631; }","duration":"130.393386ms","start":"2026-03-18T05:49:34.811902Z","end":"2026-03-18T05:49:34.942295Z","steps":["trace[571287679] 'agreement among raft nodes before linearized reading' (duration: 130.16771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:34.942377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.295325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T05:49:34.942433Z","caller":"traceutil/trace.go:171","msg":"trace[98802875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7631; }","duration":"110.375416ms","start":"2026-03-18T05:49:34.832045Z","end":"2026-03-18T05:49:34.94242Z","steps":["trace[98802875] 'agreement among raft nodes before linearized reading' (duration: 110.190702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:34.94241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.780473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:49:34.942487Z","caller":"traceutil/trace.go:171","msg":"trace[581796848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7631; }","duration":"142.908426ms","start":"2026-03-18T05:49:34.799561Z","end":"2026-03-18T05:49:34.942469Z","steps":["trace[581796848] 'agreement among raft nodes before linearized reading' (duration: 142.65331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:46.206171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.983722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-18T05:49:46.206284Z","caller":"traceutil/trace.go:171","msg":"trace[753069340] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:7683; }","duration":"157.148716ms","start":"2026-03-18T05:49:46.049113Z","end":"2026-03-18T05:49:46.206262Z","steps":["trace[753069340] 'range keys from in-memory index tree' (duration: 156.803088ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.215811Z","caller":"traceutil/trace.go:171","msg":"trace[410271341] linearizableReadLoop","detail":"{readStateIndex:7968; appliedIndex:7967; }","duration":"116.955954ms","start":"2026-03-18T05:49:46.098845Z","end":"2026-03-18T05:49:46.215801Z","steps":["trace[410271341] 'read index received' (duration: 116.864941ms)","trace[410271341] 'applied index is now lower than readState.Index' (duration: 90.213µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:46.215897Z","caller":"traceutil/trace.go:171","msg":"trace[1150544136] transaction","detail":"{read_only:false; response_revision:7684; number_of_response:1; }","duration":"139.996895ms","start":"2026-03-18T05:49:46.075866Z","end":"2026-03-18T05:49:46.215863Z","steps":["trace[1150544136] 'process raft request' (duration: 139.846021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:46.21594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.073416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:46.216004Z","caller":"traceutil/trace.go:171","msg":"trace[1139073896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7684; }","duration":"117.164958ms","start":"2026-03-18T05:49:46.098823Z","end":"2026-03-18T05:49:46.215988Z","steps":["trace[1139073896] 'agreement among raft nodes before linearized reading' (duration: 117.052435ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.667051Z","caller":"traceutil/trace.go:171","msg":"trace[298254962] transaction","detail":"{read_only:false; response_revision:7685; number_of_response:1; }","duration":"193.17309ms","start":"2026-03-18T05:49:46.473856Z","end":"2026-03-18T05:49:46.667029Z","steps":["trace[298254962] 'process raft request' (duration: 192.983726ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.726157Z","caller":"traceutil/trace.go:171","msg":"trace[1100005457] transaction","detail":"{read_only:false; response_revision:7686; number_of_response:1; }","duration":"248.69502ms","start":"2026-03-18T05:49:46.477443Z","end":"2026-03-18T05:49:46.726138Z","steps":["trace[1100005457] 'process raft request' (duration: 207.057493ms)","trace[1100005457] 'compare' (duration: 41.266479ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:47.263849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.153962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-18T05:49:47.263906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.765619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:47.263956Z","caller":"traceutil/trace.go:171","msg":"trace[983823141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7689; }","duration":"267.313946ms","start":"2026-03-18T05:49:46.996618Z","end":"2026-03-18T05:49:47.263931Z","steps":["trace[983823141] 'range keys from in-memory index tree' (duration: 266.952567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.263956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.421785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:49:47.264001Z","caller":"traceutil/trace.go:171","msg":"trace[131034631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7689; }","duration":"164.908712ms","start":"2026-03-18T05:49:47.099072Z","end":"2026-03-18T05:49:47.26398Z","steps":["trace[131034631] 'range keys from in-memory index tree' (duration: 164.704387ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.26404Z","caller":"traceutil/trace.go:171","msg":"trace[1369187741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7689; }","duration":"261.519257ms","start":"2026-03-18T05:49:47.002502Z","end":"2026-03-18T05:49:47.264022Z","steps":["trace[1369187741] 'range keys from in-memory index tree' (duration: 261.259331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.264001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.476103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T05:49:47.264298Z","caller":"traceutil/trace.go:171","msg":"trace[1877697718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7689; }","duration":"245.815911ms","start":"2026-03-18T05:49:47.01846Z","end":"2026-03-18T05:49:47.264276Z","steps":["trace[1877697718] 'range keys from in-memory index tree' (duration: 245.303389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.26502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.416102ms","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-03-18T05:49:47.265068Z","caller":"traceutil/trace.go:171","msg":"trace[2000817657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7689; }","duration":"268.525714ms","start":"2026-03-18T05:49:46.996531Z","end":"2026-03-18T05:49:47.265057Z","steps":["trace[2000817657] 'range keys from in-memory index tree' (duration: 267.144351ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:47.801517Z","caller":"traceutil/trace.go:171","msg":"trace[572131097] transaction","detail":"{read_only:false; response_revision:7690; number_of_response:1; }","duration":"533.345362ms","start":"2026-03-18T05:49:47.268145Z","end":"2026-03-18T05:49:47.801491Z","steps":["trace[572131097] 'process raft request' (duration: 533.204409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.801666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.268122Z","time spent":"533.466715ms","remote":"127.0.0.1:59560","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-03-18T05:49:48.220273Z","caller":"traceutil/trace.go:171","msg":"trace[659780633] transaction","detail":"{read_only:false; response_revision:7691; number_of_response:1; }","duration":"951.347199ms","start":"2026-03-18T05:49:47.2689Z","end":"2026-03-18T05:49:48.220247Z","steps":["trace[659780633] 'process raft request' (duration: 951.102783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.220526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.268869Z","time spent":"951.486412ms","remote":"127.0.0.1:59560","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-03-18T05:49:48.220669Z","caller":"traceutil/trace.go:171","msg":"trace[623544475] transaction","detail":"{read_only:false; response_revision:7692; number_of_response:1; }","duration":"951.518563ms","start":"2026-03-18T05:49:47.269089Z","end":"2026-03-18T05:49:48.220607Z","steps":["trace[623544475] 'process raft request' (duration: 951.111674ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.22073Z","caller":"traceutil/trace.go:171","msg":"trace[1406404370] linearizableReadLoop","detail":"{readStateIndex:7979; appliedIndex:7975; }","duration":"867.873339ms","start":"2026-03-18T05:49:47.352831Z","end":"2026-03-18T05:49:48.220704Z","steps":["trace[1406404370] 'read index received' (duration: 448.588081ms)","trace[1406404370] 'applied index is now lower than readState.Index' (duration: 419.280737ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:48.220765Z","caller":"traceutil/trace.go:171","msg":"trace[1305467773] transaction","detail":"{read_only:false; response_revision:7694; number_of_response:1; }","duration":"563.989536ms","start":"2026-03-18T05:49:47.656734Z","end":"2026-03-18T05:49:48.220724Z","steps":["trace[1305467773] 'process raft request' (duration: 563.830482ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.220849Z","caller":"traceutil/trace.go:171","msg":"trace[1214161125] transaction","detail":"{read_only:false; response_revision:7696; number_of_response:1; }","duration":"438.723015ms","start":"2026-03-18T05:49:47.78211Z","end":"2026-03-18T05:49:48.220833Z","steps":["trace[1214161125] 'process raft request' (duration: 438.531589ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.220811Z","caller":"traceutil/trace.go:171","msg":"trace[1059612319] transaction","detail":"{read_only:false; response_revision:7695; number_of_response:1; }","duration":"562.209163ms","start":"2026-03-18T05:49:47.658567Z","end":"2026-03-18T05:49:48.220776Z","steps":["trace[1059612319] 'process raft request' (duration: 562.037378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.220939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.656708Z","time spent":"564.14421ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:49:48.220965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.782076Z","time spent":"438.814637ms","remote":"127.0.0.1:59482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19433,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:49:48.220958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.26908Z","time spent":"951.718279ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:49:48.221014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.658553Z","time spent":"562.350607ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:48.221086Z","caller":"traceutil/trace.go:171","msg":"trace[430738986] transaction","detail":"{read_only:false; response_revision:7693; number_of_response:1; }","duration":"951.851381ms","start":"2026-03-18T05:49:47.269224Z","end":"2026-03-18T05:49:48.221075Z","steps":["trace[430738986] 'process raft request' (duration: 951.228186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.221147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.269214Z","time spent":"951.901383ms","remote":"127.0.0.1:59560","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-03-18T05:49:48.22119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.354901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:49:48.221234Z","caller":"traceutil/trace.go:171","msg":"trace[673775723] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:7696; }","duration":"868.430372ms","start":"2026-03-18T05:49:47.352793Z","end":"2026-03-18T05:49:48.221223Z","steps":["trace[673775723] 'agreement among raft nodes before linearized reading' (duration: 868.324289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.221272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.352779Z","time spent":"868.482944ms","remote":"127.0.0.1:59576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":54,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:49:48.54011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.649407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:49:48.540232Z","caller":"traceutil/trace.go:171","msg":"trace[1482148337] linearizableReadLoop","detail":"{readStateIndex:7983; appliedIndex:7982; }","duration":"319.413154ms","start":"2026-03-18T05:49:48.220799Z","end":"2026-03-18T05:49:48.540212Z","steps":["trace[1482148337] 'read index received' (duration: 189.541641ms)","trace[1482148337] 'applied index is now lower than readState.Index' (duration: 129.870143ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:48.540407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.037139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:48.540464Z","caller":"traceutil/trace.go:171","msg":"trace[443882290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7697; }","duration":"441.135312ms","start":"2026-03-18T05:49:48.099314Z","end":"2026-03-18T05:49:48.540449Z","steps":["trace[443882290] 'agreement among raft nodes before linearized reading' (duration: 441.03747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.540503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:48.099295Z","time spent":"441.199434ms","remote":"127.0.0.1:59300","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-03-18T05:49:48.540474Z","caller":"traceutil/trace.go:171","msg":"trace[1916713593] transaction","detail":"{read_only:false; response_revision:7697; number_of_response:1; }","duration":"642.16747ms","start":"2026-03-18T05:49:47.898251Z","end":"2026-03-18T05:49:48.540418Z","steps":["trace[1916713593] 'process raft request' (duration: 512.084472ms)","trace[1916713593] 'compare' (duration: 129.36084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:48.540622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.704576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-18T05:49:48.540708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:47.898225Z","time spent":"642.354675ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:48.540714Z","caller":"traceutil/trace.go:171","msg":"trace[784005970] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:7697; }","duration":"315.87126ms","start":"2026-03-18T05:49:48.224823Z","end":"2026-03-18T05:49:48.540694Z","steps":["trace[784005970] 'agreement among raft nodes before linearized reading' (duration: 315.642854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.540771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:48.224797Z","time spent":"315.964132ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jnprr\" "} {"level":"info","ts":"2026-03-18T05:49:49.983468Z","caller":"traceutil/trace.go:171","msg":"trace[1402843250] transaction","detail":"{read_only:false; response_revision:7699; number_of_response:1; }","duration":"172.148876ms","start":"2026-03-18T05:49:49.811282Z","end":"2026-03-18T05:49:49.983431Z","steps":["trace[1402843250] 'process raft request' (duration: 171.940651ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:50.447464Z","caller":"traceutil/trace.go:171","msg":"trace[359382902] transaction","detail":"{read_only:false; response_revision:7701; number_of_response:1; }","duration":"218.392074ms","start":"2026-03-18T05:49:50.229022Z","end":"2026-03-18T05:49:50.447414Z","steps":["trace[359382902] 'process raft request' (duration: 124.916374ms)","trace[359382902] 'compare' (duration: 93.081461ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:50.447737Z","caller":"traceutil/trace.go:171","msg":"trace[1714387190] transaction","detail":"{read_only:false; response_revision:7702; number_of_response:1; }","duration":"217.643215ms","start":"2026-03-18T05:49:50.230058Z","end":"2026-03-18T05:49:50.447701Z","steps":["trace[1714387190] 'process raft request' (duration: 217.128833ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:50.447359Z","caller":"traceutil/trace.go:171","msg":"trace[997683658] transaction","detail":"{read_only:false; response_revision:7704; number_of_response:1; }","duration":"215.505305ms","start":"2026-03-18T05:49:50.231827Z","end":"2026-03-18T05:49:50.447332Z","steps":["trace[997683658] 'process raft request' (duration: 215.457604ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:50.447788Z","caller":"traceutil/trace.go:171","msg":"trace[1626343613] transaction","detail":"{read_only:false; response_revision:7703; number_of_response:1; }","duration":"216.481958ms","start":"2026-03-18T05:49:50.231277Z","end":"2026-03-18T05:49:50.447759Z","steps":["trace[1626343613] 'process raft request' (duration: 215.953016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:51.994074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.59077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T05:49:52.649671Z","caller":"traceutil/trace.go:171","msg":"trace[68616613] linearizableReadLoop","detail":"{readStateIndex:7996; appliedIndex:7995; }","duration":"197.232247ms","start":"2026-03-18T05:49:52.452413Z","end":"2026-03-18T05:49:52.649646Z","steps":["trace[68616613] 'read index received' (duration: 181.446549ms)","trace[68616613] 'applied index is now lower than readState.Index' (duration: 15.784578ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:52.649804Z","caller":"traceutil/trace.go:171","msg":"trace[80566115] transaction","detail":"{read_only:false; response_revision:7709; number_of_response:1; }","duration":"338.439991ms","start":"2026-03-18T05:49:52.311333Z","end":"2026-03-18T05:49:52.649773Z","steps":["trace[80566115] 'process raft request' (duration: 322.446617ms)","trace[80566115] 'compare' (duration: 15.558952ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:52.649891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.469493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:49:52.649925Z","caller":"traceutil/trace.go:171","msg":"trace[1099516305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7709; }","duration":"197.554845ms","start":"2026-03-18T05:49:52.45236Z","end":"2026-03-18T05:49:52.649915Z","steps":["trace[1099516305] 'agreement among raft nodes before linearized reading' (duration: 197.399951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:52.650108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:52.311311Z","time spent":"338.576244ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:52.651725Z","caller":"traceutil/trace.go:171","msg":"trace[1820160141] transaction","detail":"{read_only:false; response_revision:7710; number_of_response:1; }","duration":"199.007359ms","start":"2026-03-18T05:49:52.452699Z","end":"2026-03-18T05:49:52.651706Z","steps":["trace[1820160141] 'process raft request' (duration: 198.889687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:52.655136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.9522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T05:49:52.655252Z","caller":"traceutil/trace.go:171","msg":"trace[1434205666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7711; }","duration":"202.100844ms","start":"2026-03-18T05:49:52.453131Z","end":"2026-03-18T05:49:52.655232Z","steps":["trace[1434205666] 'agreement among raft nodes before linearized reading' (duration: 201.895169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:52.655237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.118359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T05:49:52.65543Z","caller":"traceutil/trace.go:171","msg":"trace[148485911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7711; }","duration":"201.363236ms","start":"2026-03-18T05:49:52.45405Z","end":"2026-03-18T05:49:52.655413Z","steps":["trace[148485911] 'agreement among raft nodes before linearized reading' (duration: 201.041097ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:03.244164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5746} {"level":"info","ts":"2026-03-18T05:50:03.336907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5746,"took":"90.660582ms","hash":890037619,"current-db-size-bytes":44539904,"current-db-size":"44 MB","current-db-size-in-use-bytes":23969792,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-18T05:50:03.336966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":890037619,"revision":5746,"compact-revision":2744} {"level":"warn","ts":"2026-03-18T05:50:18.764542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360974463627797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T05:50:18.996357Z","caller":"traceutil/trace.go:171","msg":"trace[781005516] transaction","detail":"{read_only:false; response_revision:7893; number_of_response:1; }","duration":"846.550118ms","start":"2026-03-18T05:50:18.149783Z","end":"2026-03-18T05:50:18.996333Z","steps":["trace[781005516] 'process raft request' (duration: 846.381134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:18.996512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.149767Z","time spent":"846.669091ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:19.656658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.64015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:50:19.657149Z","caller":"traceutil/trace.go:171","msg":"trace[1629256777] transaction","detail":"{read_only:false; response_revision:7895; number_of_response:1; }","duration":"917.981158ms","start":"2026-03-18T05:50:18.739138Z","end":"2026-03-18T05:50:19.657119Z","steps":["trace[1629256777] 'process raft request' (duration: 917.63324ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:19.657178Z","caller":"traceutil/trace.go:171","msg":"trace[1898750879] transaction","detail":"{read_only:false; response_revision:7894; number_of_response:1; }","duration":"920.608862ms","start":"2026-03-18T05:50:18.736452Z","end":"2026-03-18T05:50:19.657061Z","steps":["trace[1898750879] 'process raft request' (duration: 557.434849ms)","trace[1898750879] 'compare' (duration: 362.162709ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:19.657274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.739119Z","time spent":"918.090392ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:19.657346Z","caller":"traceutil/trace.go:171","msg":"trace[631276681] linearizableReadLoop","detail":"{readStateIndex:8191; appliedIndex:8188; }","duration":"1.393769761s","start":"2026-03-18T05:50:18.263552Z","end":"2026-03-18T05:50:19.657322Z","steps":["trace[631276681] 'read index received' (duration: 732.781201ms)","trace[631276681] 'applied index is now lower than readState.Index' (duration: 660.98715ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:19.657382Z","caller":"traceutil/trace.go:171","msg":"trace[1011089897] transaction","detail":"{read_only:false; response_revision:7896; number_of_response:1; }","duration":"915.474309ms","start":"2026-03-18T05:50:18.74187Z","end":"2026-03-18T05:50:19.657344Z","steps":["trace[1011089897] 'process raft request' (duration: 915.010498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.657286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.736429Z","time spent":"920.779846ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:19.657567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.741859Z","time spent":"915.614592ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:19.65765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394067188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-18T05:50:19.657727Z","caller":"traceutil/trace.go:171","msg":"trace[1409663660] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7896; }","duration":"1.394158281s","start":"2026-03-18T05:50:18.263547Z","end":"2026-03-18T05:50:19.657706Z","steps":["trace[1409663660] 'agreement among raft nodes before linearized reading' (duration: 1.393926946s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.657734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.536219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-18T05:50:19.657772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.263539Z","time spent":"1.394223453s","remote":"127.0.0.1:59452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-03-18T05:50:19.657794Z","caller":"traceutil/trace.go:171","msg":"trace[567360133] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:7896; }","duration":"864.58862ms","start":"2026-03-18T05:50:18.793169Z","end":"2026-03-18T05:50:19.657758Z","steps":["trace[567360133] 'agreement among raft nodes before linearized reading' (duration: 864.513888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.657823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.793159Z","time spent":"864.657182ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jnprr\" "} {"level":"warn","ts":"2026-03-18T05:50:19.657831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.329518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-18T05:50:19.657868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.331432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-18T05:50:19.657889Z","caller":"traceutil/trace.go:171","msg":"trace[1834789035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7896; }","duration":"528.390253ms","start":"2026-03-18T05:50:19.129492Z","end":"2026-03-18T05:50:19.657883Z","steps":["trace[1834789035] 'agreement among raft nodes before linearized reading' (duration: 528.333892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.657905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.129468Z","time spent":"528.433225ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:50:19.657897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.294351ms","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-03-18T05:50:19.657918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.790679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:50:19.657939Z","caller":"traceutil/trace.go:171","msg":"trace[6046786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7896; }","duration":"881.357033ms","start":"2026-03-18T05:50:18.77657Z","end":"2026-03-18T05:50:19.657927Z","steps":["trace[6046786] 'agreement among raft nodes before linearized reading' (duration: 881.216169ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:19.65787Z","caller":"traceutil/trace.go:171","msg":"trace[1384254509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7896; }","duration":"880.40933ms","start":"2026-03-18T05:50:18.777454Z","end":"2026-03-18T05:50:19.657863Z","steps":["trace[1384254509] 'agreement among raft nodes before linearized reading' (duration: 880.272736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.657966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.012031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T05:50:19.657987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.777434Z","time spent":"880.548283ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T05:50:19.657955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.200934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:19.658016Z","caller":"traceutil/trace.go:171","msg":"trace[98983666] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:7896; }","duration":"106.105903ms","start":"2026-03-18T05:50:19.551898Z","end":"2026-03-18T05:50:19.658004Z","steps":["trace[98983666] 'agreement among raft nodes before linearized reading' (duration: 106.007821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.657732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.443966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:50:19.657948Z","caller":"traceutil/trace.go:171","msg":"trace[2110072253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7896; }","duration":"875.83942ms","start":"2026-03-18T05:50:18.782104Z","end":"2026-03-18T05:50:19.657943Z","steps":["trace[2110072253] 'agreement among raft nodes before linearized reading' (duration: 875.778298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.658191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.782093Z","time spent":"876.091916ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:50:19.657986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.776559Z","time spent":"881.418254ms","remote":"127.0.0.1:59560","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-03-18T05:50:19.658122Z","caller":"traceutil/trace.go:171","msg":"trace[632888817] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:7896; }","duration":"912.873477ms","start":"2026-03-18T05:50:18.74524Z","end":"2026-03-18T05:50:19.658113Z","steps":["trace[632888817] 'agreement among raft nodes before linearized reading' (duration: 912.440906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.658281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.745226Z","time spent":"913.019671ms","remote":"127.0.0.1:55366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-03-18T05:50:19.658065Z","caller":"traceutil/trace.go:171","msg":"trace[158651706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7896; }","duration":"557.353487ms","start":"2026-03-18T05:50:19.100691Z","end":"2026-03-18T05:50:19.658044Z","steps":["trace[158651706] 'agreement among raft nodes before linearized reading' (duration: 557.140132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.658903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.100669Z","time spent":"558.218097ms","remote":"127.0.0.1:59300","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-03-18T05:50:21.857814Z","caller":"traceutil/trace.go:171","msg":"trace[1183811260] linearizableReadLoop","detail":"{readStateIndex:8199; appliedIndex:8197; }","duration":"183.99261ms","start":"2026-03-18T05:50:21.673801Z","end":"2026-03-18T05:50:21.857793Z","steps":["trace[1183811260] 'read index received' (duration: 132.609768ms)","trace[1183811260] 'applied index is now lower than readState.Index' (duration: 51.381852ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:21.857853Z","caller":"traceutil/trace.go:171","msg":"trace[1622854266] transaction","detail":"{read_only:false; response_revision:7904; number_of_response:1; }","duration":"192.197676ms","start":"2026-03-18T05:50:21.665634Z","end":"2026-03-18T05:50:21.857832Z","steps":["trace[1622854266] 'process raft request' (duration: 192.024832ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:21.857882Z","caller":"traceutil/trace.go:171","msg":"trace[2086624649] transaction","detail":"{read_only:false; response_revision:7903; number_of_response:1; }","duration":"192.242678ms","start":"2026-03-18T05:50:21.665611Z","end":"2026-03-18T05:50:21.857853Z","steps":["trace[2086624649] 'process raft request' (duration: 140.813015ms)","trace[2086624649] 'compare' (duration: 51.051764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:21.858025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.202945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-18T05:50:21.858054Z","caller":"traceutil/trace.go:171","msg":"trace[295618033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7904; }","duration":"184.272116ms","start":"2026-03-18T05:50:21.673774Z","end":"2026-03-18T05:50:21.858046Z","steps":["trace[295618033] 'agreement among raft nodes before linearized reading' (duration: 184.149903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:21.858142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.779827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T05:50:21.85817Z","caller":"traceutil/trace.go:171","msg":"trace[511973047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7904; }","duration":"181.828248ms","start":"2026-03-18T05:50:21.676335Z","end":"2026-03-18T05:50:21.858164Z","steps":["trace[511973047] 'agreement among raft nodes before linearized reading' (duration: 181.707075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:21.85818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.559915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-18T05:50:21.858163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.885222ms","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-03-18T05:50:21.858204Z","caller":"traceutil/trace.go:171","msg":"trace[2123104414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7904; }","duration":"182.582476ms","start":"2026-03-18T05:50:21.675616Z","end":"2026-03-18T05:50:21.858199Z","steps":["trace[2123104414] 'agreement among raft nodes before linearized reading' (duration: 182.522784ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:21.85824Z","caller":"traceutil/trace.go:171","msg":"trace[527368309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7904; }","duration":"182.977135ms","start":"2026-03-18T05:50:21.675244Z","end":"2026-03-18T05:50:21.858221Z","steps":["trace[527368309] 'agreement among raft nodes before linearized reading' (duration: 182.74411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:21.858248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.86569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-18T05:50:21.858289Z","caller":"traceutil/trace.go:171","msg":"trace[879131179] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:7904; }","duration":"179.951153ms","start":"2026-03-18T05:50:21.678327Z","end":"2026-03-18T05:50:21.858278Z","steps":["trace[879131179] 'agreement among raft nodes before linearized reading' (duration: 179.84386ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:24.141913Z","caller":"traceutil/trace.go:171","msg":"trace[562625455] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"462.068094ms","start":"2026-03-18T05:50:23.679819Z","end":"2026-03-18T05:50:24.141887Z","steps":["trace[562625455] 'process raft request' (duration: 461.865099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.142069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.679791Z","time spent":"462.203766ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:24.429666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.523198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:50:24.429995Z","caller":"traceutil/trace.go:171","msg":"trace[1133344658] linearizableReadLoop","detail":"{readStateIndex:8209; appliedIndex:8205; }","duration":"560.709907ms","start":"2026-03-18T05:50:23.869264Z","end":"2026-03-18T05:50:24.429974Z","steps":["trace[1133344658] 'read index received' (duration: 272.377167ms)","trace[1133344658] 'applied index is now lower than readState.Index' (duration: 288.3315ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:24.430057Z","caller":"traceutil/trace.go:171","msg":"trace[1926247729] transaction","detail":"{read_only:false; response_revision:7911; number_of_response:1; }","duration":"672.036035ms","start":"2026-03-18T05:50:23.757982Z","end":"2026-03-18T05:50:24.430018Z","steps":["trace[1926247729] 'process raft request' (duration: 526.083217ms)","trace[1926247729] 'compare' (duration: 145.356974ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:24.430181Z","caller":"traceutil/trace.go:171","msg":"trace[1866088225] transaction","detail":"{read_only:false; response_revision:7912; number_of_response:1; }","duration":"566.930796ms","start":"2026-03-18T05:50:23.863238Z","end":"2026-03-18T05:50:24.430169Z","steps":["trace[1866088225] 'process raft request' (duration: 566.598959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.430223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.757957Z","time spent":"672.179649ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:24.43032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.863213Z","time spent":"567.031528ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:24.430369Z","caller":"traceutil/trace.go:171","msg":"trace[430813304] transaction","detail":"{read_only:false; response_revision:7913; number_of_response:1; }","duration":"567.09063ms","start":"2026-03-18T05:50:23.863242Z","end":"2026-03-18T05:50:24.430332Z","steps":["trace[430813304] 'process raft request' (duration: 566.693311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.430433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.130627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-18T05:50:24.430479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.5095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:50:24.430475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.358383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:50:24.430528Z","caller":"traceutil/trace.go:171","msg":"trace[416887127] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:7913; }","duration":"561.27189ms","start":"2026-03-18T05:50:23.869235Z","end":"2026-03-18T05:50:24.430507Z","steps":["trace[416887127] 'agreement among raft nodes before linearized reading' (duration: 561.028915ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:24.430543Z","caller":"traceutil/trace.go:171","msg":"trace[174291666] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:7913; }","duration":"166.605392ms","start":"2026-03-18T05:50:24.263918Z","end":"2026-03-18T05:50:24.430523Z","steps":["trace[174291666] 'agreement among raft nodes before linearized reading' (duration: 166.479029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.43053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.107317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T05:50:24.430545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.863217Z","time spent":"567.236353ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:24.430571Z","caller":"traceutil/trace.go:171","msg":"trace[1010058718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7913; }","duration":"556.493966ms","start":"2026-03-18T05:50:23.874058Z","end":"2026-03-18T05:50:24.430552Z","steps":["trace[1010058718] 'agreement among raft nodes before linearized reading' (duration: 556.229919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.430581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.869226Z","time spent":"561.347642ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jnprr\" "} {"level":"info","ts":"2026-03-18T05:50:24.430603Z","caller":"traceutil/trace.go:171","msg":"trace[955551186] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:7913; }","duration":"208.24369ms","start":"2026-03-18T05:50:24.222338Z","end":"2026-03-18T05:50:24.430582Z","steps":["trace[955551186] 'agreement among raft nodes before linearized reading' (duration: 208.074786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.430601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.374767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-18T05:50:24.430547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.828804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-18T05:50:24.430651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.896127ms","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-03-18T05:50:24.430657Z","caller":"traceutil/trace.go:171","msg":"trace[20007935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7913; }","duration":"547.45188ms","start":"2026-03-18T05:50:23.88319Z","end":"2026-03-18T05:50:24.430642Z","steps":["trace[20007935] 'agreement among raft nodes before linearized reading' (duration: 547.295696ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:24.430701Z","caller":"traceutil/trace.go:171","msg":"trace[2037253127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7913; }","duration":"546.956338ms","start":"2026-03-18T05:50:23.883728Z","end":"2026-03-18T05:50:24.430685Z","steps":["trace[2037253127] 'agreement among raft nodes before linearized reading' (duration: 546.848465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.430705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.883179Z","time spent":"547.515731ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T05:50:24.430692Z","caller":"traceutil/trace.go:171","msg":"trace[1896850539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7913; }","duration":"546.979478ms","start":"2026-03-18T05:50:23.883688Z","end":"2026-03-18T05:50:24.430667Z","steps":["trace[1896850539] 'agreement among raft nodes before linearized reading' (duration: 546.742013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.430626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.874046Z","time spent":"556.570988ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:50:24.430751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.883723Z","time spent":"547.017409ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T05:50:24.430788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.883679Z","time spent":"547.08344ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:50:24.43085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.617643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:24.431161Z","caller":"traceutil/trace.go:171","msg":"trace[349904595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7913; }","duration":"330.705965ms","start":"2026-03-18T05:50:24.100182Z","end":"2026-03-18T05:50:24.430888Z","steps":["trace[349904595] 'agreement among raft nodes before linearized reading' (duration: 330.527391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.431208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.100166Z","time spent":"331.033033ms","remote":"127.0.0.1:59300","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-03-18T05:50:24.861399Z","caller":"traceutil/trace.go:171","msg":"trace[1102232407] transaction","detail":"{read_only:false; response_revision:7914; number_of_response:1; }","duration":"426.504522ms","start":"2026-03-18T05:50:24.434869Z","end":"2026-03-18T05:50:24.861374Z","steps":["trace[1102232407] 'process raft request' (duration: 426.318037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.861579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.434851Z","time spent":"426.624424ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:24.952191Z","caller":"traceutil/trace.go:171","msg":"trace[1259910150] transaction","detail":"{read_only:false; response_revision:7916; number_of_response:1; }","duration":"515.631247ms","start":"2026-03-18T05:50:24.436541Z","end":"2026-03-18T05:50:24.952173Z","steps":["trace[1259910150] 'process raft request' (duration: 515.583726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.952314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.436533Z","time spent":"515.717789ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:24.952497Z","caller":"traceutil/trace.go:171","msg":"trace[33594448] transaction","detail":"{read_only:false; response_revision:7915; number_of_response:1; }","duration":"516.703183ms","start":"2026-03-18T05:50:24.435694Z","end":"2026-03-18T05:50:24.952397Z","steps":["trace[33594448] 'process raft request' (duration: 516.299463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.952666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.435667Z","time spent":"516.917239ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:24.953177Z","caller":"traceutil/trace.go:171","msg":"trace[1443091858] transaction","detail":"{read_only:false; response_revision:7917; number_of_response:1; }","duration":"516.15089ms","start":"2026-03-18T05:50:24.437017Z","end":"2026-03-18T05:50:24.953168Z","steps":["trace[1443091858] 'process raft request' (duration: 515.129374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.953224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.436966Z","time spent":"516.234371ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:24.953293Z","caller":"traceutil/trace.go:171","msg":"trace[1619251065] linearizableReadLoop","detail":"{readStateIndex:8211; appliedIndex:8209; }","duration":"517.449441ms","start":"2026-03-18T05:50:24.435828Z","end":"2026-03-18T05:50:24.953278Z","steps":["trace[1619251065] 'read index received' (duration: 425.286732ms)","trace[1619251065] 'applied index is now lower than readState.Index' (duration: 92.161819ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:24.953395Z","caller":"traceutil/trace.go:171","msg":"trace[1354438642] transaction","detail":"{read_only:false; response_revision:7918; number_of_response:1; }","duration":"505.184686ms","start":"2026-03-18T05:50:24.448181Z","end":"2026-03-18T05:50:24.953366Z","steps":["trace[1354438642] 'process raft request' (duration: 504.887449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.953538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.448171Z","time spent":"505.29356ms","remote":"127.0.0.1:38648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3657,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:24.953757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.909012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228857"} {"level":"info","ts":"2026-03-18T05:50:24.95383Z","caller":"traceutil/trace.go:171","msg":"trace[221362995] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7918; }","duration":"518.014615ms","start":"2026-03-18T05:50:24.4358Z","end":"2026-03-18T05:50:24.953815Z","steps":["trace[221362995] 'agreement among raft nodes before linearized reading' (duration: 517.566494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.953873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:24.435789Z","time spent":"518.074716ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228880,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-18T05:50:31.837911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.057612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:50:31.838304Z","caller":"traceutil/trace.go:171","msg":"trace[1618400952] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"448.327045ms","start":"2026-03-18T05:50:31.389965Z","end":"2026-03-18T05:50:31.838292Z","steps":["trace[1618400952] 'process raft request' (duration: 86.823071ms)","trace[1618400952] 'compare' (duration: 360.95312ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:31.838345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:31.389958Z","time spent":"448.368815ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:31.838535Z","caller":"traceutil/trace.go:171","msg":"trace[1159950684] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7993; }","duration":"448.073078ms","start":"2026-03-18T05:50:31.390453Z","end":"2026-03-18T05:50:31.838526Z","steps":["trace[1159950684] 'process raft request' (duration: 447.545125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:31.838607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:31.39044Z","time spent":"448.115459ms","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-03-18T05:50:31.988141Z","caller":"traceutil/trace.go:171","msg":"trace[1869467827] transaction","detail":"{read_only:false; response_revision:7994; number_of_response:1; }","duration":"139.606975ms","start":"2026-03-18T05:50:31.848507Z","end":"2026-03-18T05:50:31.988114Z","steps":["trace[1869467827] 'process raft request' (duration: 105.031437ms)","trace[1869467827] 'compare' (duration: 34.366623ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:31.988154Z","caller":"traceutil/trace.go:171","msg":"trace[2032056619] linearizableReadLoop","detail":"{readStateIndex:8294; appliedIndex:8293; }","duration":"138.599041ms","start":"2026-03-18T05:50:31.849513Z","end":"2026-03-18T05:50:31.988112Z","steps":["trace[2032056619] 'read index received' (duration: 104.047744ms)","trace[2032056619] 'applied index is now lower than readState.Index' (duration: 34.547697ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:32.011123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.549671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x4jsq\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-03-18T05:50:32.011217Z","caller":"traceutil/trace.go:171","msg":"trace[1143212866] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x4jsq; range_end:; response_count:1; response_revision:7994; }","duration":"161.728035ms","start":"2026-03-18T05:50:31.849462Z","end":"2026-03-18T05:50:32.01119Z","steps":["trace[1143212866] 'agreement among raft nodes before linearized reading' (duration: 138.744704ms)","trace[1143212866] 'range keys from in-memory index tree' (duration: 22.736475ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:32.4624Z","caller":"traceutil/trace.go:171","msg":"trace[743244694] linearizableReadLoop","detail":"{readStateIndex:8298; appliedIndex:8297; }","duration":"120.637121ms","start":"2026-03-18T05:50:32.341736Z","end":"2026-03-18T05:50:32.462373Z","steps":["trace[743244694] 'read index received' (duration: 120.449347ms)","trace[743244694] 'applied index is now lower than readState.Index' (duration: 183.414µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:32.462458Z","caller":"traceutil/trace.go:171","msg":"trace[1702660954] transaction","detail":"{read_only:false; response_revision:7997; number_of_response:1; }","duration":"153.454587ms","start":"2026-03-18T05:50:32.308968Z","end":"2026-03-18T05:50:32.462422Z","steps":["trace[1702660954] 'process raft request' (duration: 153.208291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:32.462616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.867777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x4jsq\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-03-18T05:50:32.462655Z","caller":"traceutil/trace.go:171","msg":"trace[1277140630] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x4jsq; range_end:; response_count:1; response_revision:7997; }","duration":"120.963379ms","start":"2026-03-18T05:50:32.341682Z","end":"2026-03-18T05:50:32.462645Z","steps":["trace[1277140630] 'agreement among raft nodes before linearized reading' (duration: 120.789275ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:32.545843Z","caller":"traceutil/trace.go:171","msg":"trace[1159208545] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"191.627662ms","start":"2026-03-18T05:50:32.35419Z","end":"2026-03-18T05:50:32.545817Z","steps":["trace[1159208545] 'process raft request' (duration: 191.482598ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:32.546031Z","caller":"traceutil/trace.go:171","msg":"trace[1914010601] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"203.717042ms","start":"2026-03-18T05:50:32.34229Z","end":"2026-03-18T05:50:32.546007Z","steps":["trace[1914010601] 'process raft request' (duration: 192.689548ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.066309Z","caller":"traceutil/trace.go:171","msg":"trace[1142917033] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"172.383082ms","start":"2026-03-18T05:50:32.893897Z","end":"2026-03-18T05:50:33.06628Z","steps":["trace[1142917033] 'process raft request' (duration: 172.060314ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.369578Z","caller":"traceutil/trace.go:171","msg":"trace[1111576769] linearizableReadLoop","detail":"{readStateIndex:8305; appliedIndex:8303; }","duration":"387.23699ms","start":"2026-03-18T05:50:32.982318Z","end":"2026-03-18T05:50:33.369555Z","steps":["trace[1111576769] 'read index received' (duration: 83.686385ms)","trace[1111576769] 'applied index is now lower than readState.Index' (duration: 303.545005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:33.369888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.552618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T05:50:33.36992Z","caller":"traceutil/trace.go:171","msg":"trace[300752889] transaction","detail":"{read_only:false; response_revision:8004; number_of_response:1; }","duration":"468.023527ms","start":"2026-03-18T05:50:32.901869Z","end":"2026-03-18T05:50:33.369892Z","steps":["trace[300752889] 'process raft request' (duration: 453.11831ms)","trace[300752889] 'compare' (duration: 14.348694ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:33.36996Z","caller":"traceutil/trace.go:171","msg":"trace[787741765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8004; }","duration":"387.677261ms","start":"2026-03-18T05:50:32.982271Z","end":"2026-03-18T05:50:33.369948Z","steps":["trace[787741765] 'agreement among raft nodes before linearized reading' (duration: 387.449856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:33.36999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:32.982254Z","time spent":"387.728432ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T05:50:33.370003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.23573ms","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-03-18T05:50:33.370029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:32.901855Z","time spent":"468.108539ms","remote":"127.0.0.1:46864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:33.370029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.330523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-18T05:50:33.370055Z","caller":"traceutil/trace.go:171","msg":"trace[1509441962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8004; }","duration":"228.337721ms","start":"2026-03-18T05:50:33.141704Z","end":"2026-03-18T05:50:33.370042Z","steps":["trace[1509441962] 'agreement among raft nodes before linearized reading' (duration: 228.191608ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.370074Z","caller":"traceutil/trace.go:171","msg":"trace[1942841046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8004; }","duration":"387.396864ms","start":"2026-03-18T05:50:32.982668Z","end":"2026-03-18T05:50:33.370064Z","steps":["trace[1942841046] 'agreement among raft nodes before linearized reading' (duration: 387.143198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:33.370077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.697498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-18T05:50:33.370097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.053657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnprr\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-18T05:50:33.370154Z","caller":"traceutil/trace.go:171","msg":"trace[737242948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8004; }","duration":"381.80163ms","start":"2026-03-18T05:50:32.988336Z","end":"2026-03-18T05:50:33.370138Z","steps":["trace[737242948] 'agreement among raft nodes before linearized reading' (duration: 381.586825ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.370173Z","caller":"traceutil/trace.go:171","msg":"trace[813118591] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnprr; range_end:; response_count:1; response_revision:8004; }","duration":"367.181459ms","start":"2026-03-18T05:50:33.002977Z","end":"2026-03-18T05:50:33.370159Z","steps":["trace[813118591] 'agreement among raft nodes before linearized reading' (duration: 366.937063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:33.370209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:32.988326Z","time spent":"381.874522ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:50:33.370213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:33.002965Z","time spent":"367.239721ms","remote":"127.0.0.1:59486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jnprr\" "} {"level":"warn","ts":"2026-03-18T05:50:33.370104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:32.982652Z","time spent":"387.441306ms","remote":"127.0.0.1:59560","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-03-18T05:50:45.434298Z","caller":"traceutil/trace.go:171","msg":"trace[2125614017] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"106.032551ms","start":"2026-03-18T05:50:45.328243Z","end":"2026-03-18T05:50:45.434276Z","steps":["trace[2125614017] 'process raft request' (duration: 105.901928ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:50.746055Z","caller":"traceutil/trace.go:171","msg":"trace[1064972391] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"257.613651ms","start":"2026-03-18T05:51:50.48841Z","end":"2026-03-18T05:51:50.746024Z","steps":["trace[1064972391] 'process raft request' (duration: 257.444147ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:51.08384Z","caller":"traceutil/trace.go:171","msg":"trace[151619114] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"255.87231ms","start":"2026-03-18T05:51:50.827942Z","end":"2026-03-18T05:51:51.083814Z","steps":["trace[151619114] 'process raft request' (duration: 255.738556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.430207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.119209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:51:51.430332Z","caller":"traceutil/trace.go:171","msg":"trace[638892257] linearizableReadLoop","detail":"{readStateIndex:9039; appliedIndex:9038; }","duration":"330.503771ms","start":"2026-03-18T05:51:51.099812Z","end":"2026-03-18T05:51:51.430315Z","steps":["trace[638892257] 'read index received' (duration: 145.068754ms)","trace[638892257] 'applied index is now lower than readState.Index' (duration: 185.433397ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:51:51.43035Z","caller":"traceutil/trace.go:171","msg":"trace[980277683] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"590.046167ms","start":"2026-03-18T05:51:50.840275Z","end":"2026-03-18T05:51:51.430321Z","steps":["trace[980277683] 'process raft request' (duration: 404.698012ms)","trace[980277683] 'compare' (duration: 184.881394ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:51:51.430448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.640724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:51:51.430485Z","caller":"traceutil/trace.go:171","msg":"trace[1787044252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8718; }","duration":"330.716266ms","start":"2026-03-18T05:51:51.09976Z","end":"2026-03-18T05:51:51.430476Z","steps":["trace[1787044252] 'agreement among raft nodes before linearized reading' (duration: 330.645755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.430488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:50.840253Z","time spent":"590.165759ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:51:51.430527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.099741Z","time spent":"330.775027ms","remote":"127.0.0.1:59300","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-03-18T05:51:51.719351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.892448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:51:51.719492Z","caller":"traceutil/trace.go:171","msg":"trace[661904827] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"285.359464ms","start":"2026-03-18T05:51:51.434103Z","end":"2026-03-18T05:51:51.719462Z","steps":["trace[661904827] 'process raft request' (duration: 166.27459ms)","trace[661904827] 'compare' (duration: 118.781495ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:54:05.468855Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30aa8136fb61a722","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T05:54:05.475371Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-18T05:54:05.475474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-18T05:54:07.320483Z","caller":"traceutil/trace.go:171","msg":"trace[766730257] transaction","detail":"{read_only:false; response_revision:9677; number_of_response:1; }","duration":"111.770112ms","start":"2026-03-18T05:54:07.208684Z","end":"2026-03-18T05:54:07.320454Z","steps":["trace[766730257] 'process raft request' (duration: 111.573937ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:55:03.250116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7771} {"level":"info","ts":"2026-03-18T05:55:03.313585Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7771,"took":"61.578245ms","hash":4255252701,"current-db-size-bytes":44539904,"current-db-size":"44 MB","current-db-size-in-use-bytes":26394624,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-18T05:55:03.313643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4255252701,"revision":7771,"compact-revision":5746} {"level":"info","ts":"2026-03-18T06:00:03.255607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10004} {"level":"info","ts":"2026-03-18T06:00:03.329972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10004,"took":"72.770335ms","hash":2443359761,"current-db-size-bytes":44539904,"current-db-size":"44 MB","current-db-size-in-use-bytes":17182720,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-18T06:00:03.330025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2443359761,"revision":10004,"compact-revision":7771}