{"level":"warn","ts":"2026-03-14T07:00:05.15393Z","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-14T07:00:05.154046Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221: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-14T07:00:05.154543Z","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-14T07:00:05.154563Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-14T07:00:05.154609Z","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-14T07:00:05.155309Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-03-14T07:00:05.155419Z","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.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221: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-14T07:00:05.159883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.2097ms"} {"level":"info","ts":"2026-03-14T07:00:05.167077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-03-14T07:00:05.167224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T07:00:05.167287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-03-14T07:00:05.16731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T07:00:05.167352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-03-14T07:00:05.167423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-03-14T07:00:05.177702Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T07:00:05.179323Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T07:00:05.18068Z","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-14T07:00:05.183479Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T07:00:05.183684Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T07:00:05.183803Z","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-14T07:00:05.183903Z","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-14T07:00:05.183928Z","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-14T07:00:05.185715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-03-14T07:00:05.185843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-14T07:00:05.188374Z","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-14T07:00:05.188662Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T07:00:05.188706Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T07:00:05.188805Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-14T07:00:05.188894Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-14T07:00:06.068345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T07:00:06.068407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T07:00:06.068451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-03-14T07:00:06.068471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-03-14T07:00:06.068483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-14T07:00:06.068501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-03-14T07:00:06.06853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-14T07:00:06.069846Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T07:00:06.069936Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:06.070073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:06.070134Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:06.070295Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T07:00:06.070391Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T07:00:06.071197Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:06.071336Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:06.0714Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:06.073951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"info","ts":"2026-03-14T07:00:06.074349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-14T07:00:09.187052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.552255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:00:09.18722Z","caller":"traceutil/trace.go:171","msg":"trace[767200849] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"216.624075ms","start":"2026-03-14T07:00:08.970572Z","end":"2026-03-14T07:00:09.187196Z","steps":["trace[767200849] 'process raft request' (duration: 102.378454ms)","trace[767200849] 'compare' (duration: 113.421332ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:09.347884Z","caller":"traceutil/trace.go:171","msg":"trace[53338862] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"100.161472ms","start":"2026-03-14T07:00:09.247686Z","end":"2026-03-14T07:00:09.347848Z","steps":["trace[53338862] 'process raft request' (duration: 57.823358ms)","trace[53338862] 'compare' (duration: 42.14328ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:14.897513Z","caller":"traceutil/trace.go:171","msg":"trace[1593645120] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"248.665028ms","start":"2026-03-14T07:00:14.648814Z","end":"2026-03-14T07:00:14.897479Z","steps":["trace[1593645120] 'process raft request' (duration: 192.819257ms)","trace[1593645120] 'compare' (duration: 55.711087ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:14.929428Z","caller":"traceutil/trace.go:171","msg":"trace[715993363] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:318; }","duration":"241.567251ms","start":"2026-03-14T07:00:14.687845Z","end":"2026-03-14T07:00:14.929412Z","steps":["trace[715993363] 'read index received' (duration: 153.76877ms)","trace[715993363] 'applied index is now lower than readState.Index' (duration: 87.797071ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:00:14.929591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.697653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-14T07:00:14.929626Z","caller":"traceutil/trace.go:171","msg":"trace[1272282845] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:308; }","duration":"241.819806ms","start":"2026-03-14T07:00:14.687796Z","end":"2026-03-14T07:00:14.929616Z","steps":["trace[1272282845] 'agreement among raft nodes before linearized reading' (duration: 241.708084ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:14.92958Z","caller":"traceutil/trace.go:171","msg":"trace[1093891277] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"279.256836ms","start":"2026-03-14T07:00:14.6502Z","end":"2026-03-14T07:00:14.929457Z","steps":["trace[1093891277] 'process raft request' (duration: 279.140093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:14.929758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.001973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-14T07:00:14.929822Z","caller":"traceutil/trace.go:171","msg":"trace[1819727683] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:308; }","duration":"142.130035ms","start":"2026-03-14T07:00:14.787666Z","end":"2026-03-14T07:00:14.929796Z","steps":["trace[1819727683] 'agreement among raft nodes before linearized reading' (duration: 142.060544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:14.929674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.939685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-14T07:00:14.930014Z","caller":"traceutil/trace.go:171","msg":"trace[1816055984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:308; }","duration":"192.327425ms","start":"2026-03-14T07:00:14.737651Z","end":"2026-03-14T07:00:14.929978Z","steps":["trace[1816055984] 'agreement among raft nodes before linearized reading' (duration: 191.888495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:15.32123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.897089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:00:15.321429Z","caller":"traceutil/trace.go:171","msg":"trace[164546277] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:320; }","duration":"334.605685ms","start":"2026-03-14T07:00:14.986808Z","end":"2026-03-14T07:00:15.321413Z","steps":["trace[164546277] 'read index received' (duration: 106.484021ms)","trace[164546277] 'applied index is now lower than readState.Index' (duration: 228.120694ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:00:15.321534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.724658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-14T07:00:15.321525Z","caller":"traceutil/trace.go:171","msg":"trace[1508349043] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"383.105774ms","start":"2026-03-14T07:00:14.938399Z","end":"2026-03-14T07:00:15.321504Z","steps":["trace[1508349043] 'process raft request' (duration: 382.973691ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:15.321565Z","caller":"traceutil/trace.go:171","msg":"trace[707356932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:311; }","duration":"334.778138ms","start":"2026-03-14T07:00:14.986773Z","end":"2026-03-14T07:00:15.321551Z","steps":["trace[707356932] 'agreement among raft nodes before linearized reading' (duration: 334.722337ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:15.321512Z","caller":"traceutil/trace.go:171","msg":"trace[1310903727] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"383.646487ms","start":"2026-03-14T07:00:14.937787Z","end":"2026-03-14T07:00:15.321434Z","steps":["trace[1310903727] 'process raft request' (duration: 383.536724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:15.321694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.520978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-14T07:00:15.321718Z","caller":"traceutil/trace.go:171","msg":"trace[399141895] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:311; }","duration":"183.586879ms","start":"2026-03-14T07:00:15.138126Z","end":"2026-03-14T07:00:15.321713Z","steps":["trace[399141895] 'agreement among raft nodes before linearized reading' (duration: 183.553268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:15.321739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.17712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-14T07:00:15.321811Z","caller":"traceutil/trace.go:171","msg":"trace[1435965764] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"134.298173ms","start":"2026-03-14T07:00:15.1875Z","end":"2026-03-14T07:00:15.321798Z","steps":["trace[1435965764] 'agreement among raft nodes before linearized reading' (duration: 134.17487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:15.321844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:14.938392Z","time spent":"383.184116ms","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:00:15.321995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:14.93777Z","time spent":"383.990374ms","remote":"127.0.0.1:36126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:00:15.321999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.799048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-14T07:00:15.322053Z","caller":"traceutil/trace.go:171","msg":"trace[1262880221] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"383.853041ms","start":"2026-03-14T07:00:14.937606Z","end":"2026-03-14T07:00:15.321459Z","steps":["trace[1262880221] 'process raft request' (duration: 155.667965ms)","trace[1262880221] 'compare' (duration: 227.704365ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:15.322152Z","caller":"traceutil/trace.go:171","msg":"trace[2064036122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:311; }","duration":"234.012193ms","start":"2026-03-14T07:00:15.088116Z","end":"2026-03-14T07:00:15.322128Z","steps":["trace[2064036122] 'agreement among raft nodes before linearized reading' (duration: 233.777827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:15.321588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:14.986761Z","time spent":"334.82235ms","remote":"127.0.0.1:36090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-14T07:00:15.321668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.353275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-14T07:00:15.322261Z","caller":"traceutil/trace.go:171","msg":"trace[1949319225] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:311; }","duration":"284.960078ms","start":"2026-03-14T07:00:15.037288Z","end":"2026-03-14T07:00:15.322248Z","steps":["trace[1949319225] 'agreement among raft nodes before linearized reading' (duration: 284.356855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:15.322439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:14.937585Z","time spent":"384.554027ms","remote":"127.0.0.1:36140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:00:44.278742Z","caller":"traceutil/trace.go:171","msg":"trace[805403781] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"128.644097ms","start":"2026-03-14T07:00:44.150076Z","end":"2026-03-14T07:00:44.27872Z","steps":["trace[805403781] 'read index received' (duration: 128.464993ms)","trace[805403781] 'applied index is now lower than readState.Index' (duration: 178.114µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:44.278798Z","caller":"traceutil/trace.go:171","msg":"trace[1582781638] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"149.251352ms","start":"2026-03-14T07:00:44.129523Z","end":"2026-03-14T07:00:44.278774Z","steps":["trace[1582781638] 'process raft request' (duration: 149.084078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:44.278958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.858142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-14T07:00:44.279005Z","caller":"traceutil/trace.go:171","msg":"trace[1643890369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"128.953294ms","start":"2026-03-14T07:00:44.150041Z","end":"2026-03-14T07:00:44.278995Z","steps":["trace[1643890369] 'agreement among raft nodes before linearized reading' (duration: 128.77893ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:44.392125Z","caller":"traceutil/trace.go:171","msg":"trace[1710941052] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"109.241768ms","start":"2026-03-14T07:00:44.282859Z","end":"2026-03-14T07:00:44.3921Z","steps":["trace[1710941052] 'process raft request' (duration: 109.174617ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:44.392142Z","caller":"traceutil/trace.go:171","msg":"trace[1131878619] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"109.281089ms","start":"2026-03-14T07:00:44.282808Z","end":"2026-03-14T07:00:44.392089Z","steps":["trace[1131878619] 'process raft request' (duration: 75.100271ms)","trace[1131878619] 'compare' (duration: 33.996934ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:44.39245Z","caller":"traceutil/trace.go:171","msg":"trace[1865116148] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"109.360092ms","start":"2026-03-14T07:00:44.283056Z","end":"2026-03-14T07:00:44.392416Z","steps":["trace[1865116148] 'process raft request' (duration: 109.022124ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:44.54527Z","caller":"traceutil/trace.go:171","msg":"trace[911863383] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"105.01243ms","start":"2026-03-14T07:00:44.440219Z","end":"2026-03-14T07:00:44.545231Z","steps":["trace[911863383] 'process raft request' (duration: 85.857359ms)","trace[911863383] 'compare' (duration: 19.040369ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:44.609047Z","caller":"traceutil/trace.go:171","msg":"trace[1215330706] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"166.245083ms","start":"2026-03-14T07:00:44.442776Z","end":"2026-03-14T07:00:44.609021Z","steps":["trace[1215330706] 'read index received' (duration: 83.26603ms)","trace[1215330706] 'applied index is now lower than readState.Index' (duration: 82.977883ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:00:44.609057Z","caller":"traceutil/trace.go:171","msg":"trace[1676028083] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"167.330898ms","start":"2026-03-14T07:00:44.441669Z","end":"2026-03-14T07:00:44.609Z","steps":["trace[1676028083] 'process raft request' (duration: 167.110213ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:44.609274Z","caller":"traceutil/trace.go:171","msg":"trace[288013210] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"153.633332ms","start":"2026-03-14T07:00:44.455611Z","end":"2026-03-14T07:00:44.609244Z","steps":["trace[288013210] 'process raft request' (duration: 153.378427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:44.609287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.482249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8p2fr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-14T07:00:44.609304Z","caller":"traceutil/trace.go:171","msg":"trace[1949656519] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"153.769505ms","start":"2026-03-14T07:00:44.455502Z","end":"2026-03-14T07:00:44.609271Z","steps":["trace[1949656519] 'process raft request' (duration: 153.396527ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:44.609407Z","caller":"traceutil/trace.go:171","msg":"trace[1386444686] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8p2fr; range_end:; response_count:1; response_revision:575; }","duration":"166.638213ms","start":"2026-03-14T07:00:44.442751Z","end":"2026-03-14T07:00:44.609389Z","steps":["trace[1386444686] 'agreement among raft nodes before linearized reading' (duration: 166.359166ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:44.609749Z","caller":"traceutil/trace.go:171","msg":"trace[1401168168] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"154.219935ms","start":"2026-03-14T07:00:44.455512Z","end":"2026-03-14T07:00:44.609732Z","steps":["trace[1401168168] 'process raft request' (duration: 153.442248ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.136931Z","caller":"traceutil/trace.go:171","msg":"trace[22495038] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"499.513107ms","start":"2026-03-14T07:00:44.637376Z","end":"2026-03-14T07:00:45.136889Z","steps":["trace[22495038] 'process raft request' (duration: 499.332893ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.137008Z","caller":"traceutil/trace.go:171","msg":"trace[1086503148] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"491.834659ms","start":"2026-03-14T07:00:44.645166Z","end":"2026-03-14T07:00:45.137Z","steps":["trace[1086503148] 'process raft request' (duration: 491.642185ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.137041Z","caller":"traceutil/trace.go:171","msg":"trace[276458493] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"503.393705ms","start":"2026-03-14T07:00:44.633603Z","end":"2026-03-14T07:00:45.136996Z","steps":["trace[276458493] 'process raft request' (duration: 502.934265ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.137072Z","caller":"traceutil/trace.go:171","msg":"trace[2074227168] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"501.693217ms","start":"2026-03-14T07:00:44.63535Z","end":"2026-03-14T07:00:45.137043Z","steps":["trace[2074227168] 'process raft request' (duration: 501.313209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.137128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.645147Z","time spent":"491.918461ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58713,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:00:45.137208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.635323Z","time spent":"501.82517ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9589,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:00:45.137208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.633594Z","time spent":"503.535619ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8989,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:00:45.137102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.637362Z","time spent":"499.66521ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20218,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:00:45.136949Z","caller":"traceutil/trace.go:171","msg":"trace[323393958] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"491.714877ms","start":"2026-03-14T07:00:44.645227Z","end":"2026-03-14T07:00:45.136942Z","steps":["trace[323393958] 'process raft request' (duration: 491.525253ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.136934Z","caller":"traceutil/trace.go:171","msg":"trace[108983688] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"489.436814ms","start":"2026-03-14T07:00:44.647473Z","end":"2026-03-14T07:00:45.13691Z","steps":["trace[108983688] 'process raft request' (duration: 489.387243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.137376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.645187Z","time spent":"492.164517ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":72609,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:00:45.137684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.647444Z","time spent":"490.091169ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":72484,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:00:45.215233Z","caller":"traceutil/trace.go:171","msg":"trace[15547002] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:596; }","duration":"421.192901ms","start":"2026-03-14T07:00:44.794017Z","end":"2026-03-14T07:00:45.21521Z","steps":["trace[15547002] 'read index received' (duration: 342.502156ms)","trace[15547002] 'applied index is now lower than readState.Index' (duration: 78.688915ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:00:45.215487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.450647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:45.215589Z","caller":"traceutil/trace.go:171","msg":"trace[1011119364] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:583; }","duration":"421.612251ms","start":"2026-03-14T07:00:44.793964Z","end":"2026-03-14T07:00:45.215577Z","steps":["trace[1011119364] 'agreement among raft nodes before linearized reading' (duration: 421.457558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.215664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.793938Z","time spent":"421.711143ms","remote":"127.0.0.1:53748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T07:00:45.224211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.121577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:00:45.224256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.404993ms","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":"info","ts":"2026-03-14T07:00:45.224329Z","caller":"traceutil/trace.go:171","msg":"trace[618361468] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:598; }","duration":"374.508145ms","start":"2026-03-14T07:00:44.8498Z","end":"2026-03-14T07:00:45.224308Z","steps":["trace[618361468] 'agreement among raft nodes before linearized reading' (duration: 374.389372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.224379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.849784Z","time spent":"374.584736ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:00:45.224591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.169568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:45.224647Z","caller":"traceutil/trace.go:171","msg":"trace[245819160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"374.24878ms","start":"2026-03-14T07:00:44.850387Z","end":"2026-03-14T07:00:45.224635Z","steps":["trace[245819160] 'agreement among raft nodes before linearized reading' (duration: 374.158688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.224678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.850376Z","time spent":"374.295931ms","remote":"127.0.0.1:35878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:00:45.224849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.501026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:45.224907Z","caller":"traceutil/trace.go:171","msg":"trace[857877268] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:598; }","duration":"374.581687ms","start":"2026-03-14T07:00:44.850316Z","end":"2026-03-14T07:00:45.224898Z","steps":["trace[857877268] 'agreement among raft nodes before linearized reading' (duration: 374.506765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.224938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.850305Z","time spent":"374.624759ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"info","ts":"2026-03-14T07:00:45.224719Z","caller":"traceutil/trace.go:171","msg":"trace[886254859] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:598; }","duration":"430.292371ms","start":"2026-03-14T07:00:44.794Z","end":"2026-03-14T07:00:45.224292Z","steps":["trace[886254859] 'agreement among raft nodes before linearized reading' (duration: 430.113537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.225076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.14788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:45.225104Z","caller":"traceutil/trace.go:171","msg":"trace[1706321460] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:598; }","duration":"375.17746ms","start":"2026-03-14T07:00:44.849919Z","end":"2026-03-14T07:00:45.225096Z","steps":["trace[1706321460] 'agreement among raft nodes before linearized reading' (duration: 375.132029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.225141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.849869Z","time spent":"375.264083ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T07:00:45.225376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.793976Z","time spent":"431.0922ms","remote":"127.0.0.1:53748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:00:45.226251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.952852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:00:45.226329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.463983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:45.226382Z","caller":"traceutil/trace.go:171","msg":"trace[21334035] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:598; }","duration":"413.539504ms","start":"2026-03-14T07:00:44.812831Z","end":"2026-03-14T07:00:45.226371Z","steps":["trace[21334035] 'agreement among raft nodes before linearized reading' (duration: 413.33547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.226389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.442499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:00:45.226405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.812823Z","time spent":"413.577096ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:00:45.22642Z","caller":"traceutil/trace.go:171","msg":"trace[161820182] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:598; }","duration":"399.480801ms","start":"2026-03-14T07:00:44.826929Z","end":"2026-03-14T07:00:45.22641Z","steps":["trace[161820182] 'agreement among raft nodes before linearized reading' (duration: 399.43609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.226374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.389261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:00:45.226467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.826925Z","time spent":"399.533112ms","remote":"127.0.0.1:53774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-03-14T07:00:45.226503Z","caller":"traceutil/trace.go:171","msg":"trace[755689852] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:598; }","duration":"413.543444ms","start":"2026-03-14T07:00:44.812914Z","end":"2026-03-14T07:00:45.226457Z","steps":["trace[755689852] 'agreement among raft nodes before linearized reading' (duration: 413.36178ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.22634Z","caller":"traceutil/trace.go:171","msg":"trace[2011406780] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:598; }","duration":"413.070504ms","start":"2026-03-14T07:00:44.813246Z","end":"2026-03-14T07:00:45.226316Z","steps":["trace[2011406780] 'agreement among raft nodes before linearized reading' (duration: 412.91312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.226523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.061225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:00:45.226578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.813239Z","time spent":"413.33197ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-03-14T07:00:45.226329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.54951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:00:45.226602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.812891Z","time spent":"413.690618ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T07:00:45.226707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.351212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:00:45.226699Z","caller":"traceutil/trace.go:171","msg":"trace[1564706325] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:598; }","duration":"431.897918ms","start":"2026-03-14T07:00:44.794741Z","end":"2026-03-14T07:00:45.226639Z","steps":["trace[1564706325] 'agreement among raft nodes before linearized reading' (duration: 431.481549ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:00:45.226768Z","caller":"traceutil/trace.go:171","msg":"trace[1195443310] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:598; }","duration":"400.421183ms","start":"2026-03-14T07:00:44.826333Z","end":"2026-03-14T07:00:45.226755Z","steps":["trace[1195443310] 'agreement among raft nodes before linearized reading' (duration: 400.338561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.22681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.826328Z","time spent":"400.469834ms","remote":"127.0.0.1:53774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:00:45.226824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.794723Z","time spent":"432.049091ms","remote":"127.0.0.1:53748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"info","ts":"2026-03-14T07:00:45.226598Z","caller":"traceutil/trace.go:171","msg":"trace[628089697] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:598; }","duration":"400.157617ms","start":"2026-03-14T07:00:44.826418Z","end":"2026-03-14T07:00:45.226576Z","steps":["trace[628089697] 'agreement among raft nodes before linearized reading' (duration: 400.001703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:00:45.226965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:00:44.826401Z","time spent":"400.538706ms","remote":"127.0.0.1:53774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T07:01:07.916831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.098086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" ","response":"range_response_count:1 size:21237"} {"level":"info","ts":"2026-03-14T07:01:07.916948Z","caller":"traceutil/trace.go:171","msg":"trace[1944460296] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io; range_end:; response_count:1; response_revision:905; }","duration":"191.261599ms","start":"2026-03-14T07:01:07.725648Z","end":"2026-03-14T07:01:07.91691Z","steps":["trace[1944460296] 'range keys from in-memory index tree' (duration: 190.83385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:08.309474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.064585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:01:08.309771Z","caller":"traceutil/trace.go:171","msg":"trace[1952558874] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"279.091554ms","start":"2026-03-14T07:01:08.03055Z","end":"2026-03-14T07:01:08.309641Z","steps":["trace[1952558874] 'read index received' (duration: 39.601µs)","trace[1952558874] 'applied index is now lower than readState.Index' (duration: 279.049553ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:08.310031Z","caller":"traceutil/trace.go:171","msg":"trace[1100873008] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"389.068291ms","start":"2026-03-14T07:01:07.920901Z","end":"2026-03-14T07:01:08.309969Z","steps":["trace[1100873008] 'process raft request' (duration: 99.326321ms)","trace[1100873008] 'compare' (duration: 287.865077ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:08.31015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:07.920867Z","time spent":"389.235955ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:08.310878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.10179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:08.310976Z","caller":"traceutil/trace.go:171","msg":"trace[532280565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"166.240594ms","start":"2026-03-14T07:01:08.144703Z","end":"2026-03-14T07:01:08.310943Z","steps":["trace[532280565] 'agreement among raft nodes before linearized reading' (duration: 165.375553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:08.31299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.36028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:08.313175Z","caller":"traceutil/trace.go:171","msg":"trace[77752740] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:0; response_revision:907; }","duration":"282.656107ms","start":"2026-03-14T07:01:08.030485Z","end":"2026-03-14T07:01:08.313141Z","steps":["trace[77752740] 'agreement among raft nodes before linearized reading' (duration: 279.579026ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:01.688383Z","caller":"traceutil/trace.go:171","msg":"trace[430696927] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"107.209772ms","start":"2026-03-14T07:02:01.581126Z","end":"2026-03-14T07:02:01.688336Z","steps":["trace[430696927] 'process raft request' (duration: 106.964606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.241643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363236751310437268,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:02:02.252416Z","caller":"traceutil/trace.go:171","msg":"trace[1016269554] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"559.426566ms","start":"2026-03-14T07:02:01.69295Z","end":"2026-03-14T07:02:02.252377Z","steps":["trace[1016269554] 'process raft request' (duration: 559.16687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.252641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.692931Z","time spent":"559.586289ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:02.286163Z","caller":"traceutil/trace.go:171","msg":"trace[1091396112] linearizableReadLoop","detail":"{readStateIndex:1644; appliedIndex:1642; }","duration":"544.701732ms","start":"2026-03-14T07:02:01.741418Z","end":"2026-03-14T07:02:02.28612Z","steps":["trace[1091396112] 'read index received' (duration: 510.664064ms)","trace[1091396112] 'applied index is now lower than readState.Index' (duration: 34.036558ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:02.286297Z","caller":"traceutil/trace.go:171","msg":"trace[1283417890] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"592.945013ms","start":"2026-03-14T07:02:01.693335Z","end":"2026-03-14T07:02:02.28628Z","steps":["trace[1283417890] 'process raft request' (duration: 592.613325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.28645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.693324Z","time spent":"593.006634ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:02.286448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.28219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:02:02.286503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.06718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:02.286603Z","caller":"traceutil/trace.go:171","msg":"trace[1708132010] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1582; }","duration":"545.205294ms","start":"2026-03-14T07:02:01.741378Z","end":"2026-03-14T07:02:02.286583Z","steps":["trace[1708132010] 'agreement among raft nodes before linearized reading' (duration: 545.04992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.286596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.264843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e3de2f9-daaf-4712-a578-11ed81568a68\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-14T07:02:02.286608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.337447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e3de2f9-daaf-4712-a578-11ed81568a68\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-14T07:02:02.286652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.741356Z","time spent":"545.284845ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:02:02.286667Z","caller":"traceutil/trace.go:171","msg":"trace[449177585] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e3de2f9-daaf-4712-a578-11ed81568a68; range_end:; response_count:1; response_revision:1582; }","duration":"432.358505ms","start":"2026-03-14T07:02:01.854288Z","end":"2026-03-14T07:02:02.286646Z","steps":["trace[449177585] 'agreement among raft nodes before linearized reading' (duration: 432.211522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.286458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.516486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-14T07:02:02.286621Z","caller":"traceutil/trace.go:171","msg":"trace[2016476114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1582; }","duration":"141.462484ms","start":"2026-03-14T07:02:02.145109Z","end":"2026-03-14T07:02:02.286571Z","steps":["trace[2016476114] 'agreement among raft nodes before linearized reading' (duration: 141.28038ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:02.286709Z","caller":"traceutil/trace.go:171","msg":"trace[6896843] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1582; }","duration":"512.802352ms","start":"2026-03-14T07:02:01.773897Z","end":"2026-03-14T07:02:02.2867Z","steps":["trace[6896843] 'agreement among raft nodes before linearized reading' (duration: 512.430384ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:02.286673Z","caller":"traceutil/trace.go:171","msg":"trace[348413994] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6e3de2f9-daaf-4712-a578-11ed81568a68; range_end:; response_count:1; response_revision:1582; }","duration":"100.440759ms","start":"2026-03-14T07:02:02.186214Z","end":"2026-03-14T07:02:02.286655Z","steps":["trace[348413994] 'agreement among raft nodes before linearized reading' (duration: 100.276795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.286761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.773885Z","time spent":"512.867353ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-14T07:02:02.286722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.85427Z","time spent":"432.438877ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6e3de2f9-daaf-4712-a578-11ed81568a68\" "} {"level":"warn","ts":"2026-03-14T07:02:02.286951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.243601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-03-14T07:02:02.287087Z","caller":"traceutil/trace.go:171","msg":"trace[1274250430] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1582; }","duration":"501.432186ms","start":"2026-03-14T07:02:01.785621Z","end":"2026-03-14T07:02:02.287053Z","steps":["trace[1274250430] 'agreement among raft nodes before linearized reading' (duration: 501.008237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.287153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:01.785589Z","time spent":"501.552728ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-03-14T07:02:02.542157Z","caller":"traceutil/trace.go:171","msg":"trace[2026755677] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"241.74785ms","start":"2026-03-14T07:02:02.300374Z","end":"2026-03-14T07:02:02.542122Z","steps":["trace[2026755677] 'process raft request' (duration: 222.902255ms)","trace[2026755677] 'compare' (duration: 18.701162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:02.899401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.028843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:02.899511Z","caller":"traceutil/trace.go:171","msg":"trace[1292495271] linearizableReadLoop","detail":"{readStateIndex:1647; appliedIndex:1646; }","duration":"309.550972ms","start":"2026-03-14T07:02:02.589941Z","end":"2026-03-14T07:02:02.899492Z","steps":["trace[1292495271] 'read index received' (duration: 61.243854ms)","trace[1292495271] 'applied index is now lower than readState.Index' (duration: 248.305728ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:02.8996Z","caller":"traceutil/trace.go:171","msg":"trace[404689396] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"337.76409ms","start":"2026-03-14T07:02:02.561799Z","end":"2026-03-14T07:02:02.899563Z","steps":["trace[404689396] 'process raft request' (duration: 89.474262ms)","trace[404689396] 'compare' (duration: 247.822858ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:02.899742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.58919ms","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-14T07:02:02.899778Z","caller":"traceutil/trace.go:171","msg":"trace[749259122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1585; }","duration":"138.621791ms","start":"2026-03-14T07:02:02.761143Z","end":"2026-03-14T07:02:02.899765Z","steps":["trace[749259122] 'agreement among raft nodes before linearized reading' (duration: 138.449817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.899795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:02.561771Z","time spent":"337.902853ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:02.900057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.080464ms","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-14T07:02:02.900174Z","caller":"traceutil/trace.go:171","msg":"trace[771427513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1585; }","duration":"310.246698ms","start":"2026-03-14T07:02:02.589902Z","end":"2026-03-14T07:02:02.900149Z","steps":["trace[771427513] 'agreement among raft nodes before linearized reading' (duration: 309.765847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:02.900234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:02.58989Z","time spent":"310.3341ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T07:02:47.231315Z","caller":"traceutil/trace.go:171","msg":"trace[1687317921] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"101.181435ms","start":"2026-03-14T07:02:47.130099Z","end":"2026-03-14T07:02:47.23128Z","steps":["trace[1687317921] 'process raft request' (duration: 100.92122ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:47.996133Z","caller":"traceutil/trace.go:171","msg":"trace[809588050] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"153.403536ms","start":"2026-03-14T07:02:47.842701Z","end":"2026-03-14T07:02:47.996104Z","steps":["trace[809588050] 'process raft request' (duration: 153.264552ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:48.051884Z","caller":"traceutil/trace.go:171","msg":"trace[2135663387] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"110.891204ms","start":"2026-03-14T07:02:47.940956Z","end":"2026-03-14T07:02:48.051847Z","steps":["trace[2135663387] 'process raft request' (duration: 88.006228ms)","trace[2135663387] 'compare' (duration: 22.701052ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:53.529179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.723694ms","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-14T07:02:53.529267Z","caller":"traceutil/trace.go:171","msg":"trace[1949897071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1890; }","duration":"129.863048ms","start":"2026-03-14T07:02:53.399385Z","end":"2026-03-14T07:02:53.529248Z","steps":["trace[1949897071] 'range keys from in-memory index tree' (duration: 129.549781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:53.529308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.475939ms","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-14T07:02:53.529331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.524912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-14T07:02:53.529393Z","caller":"traceutil/trace.go:171","msg":"trace[398014925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1890; }","duration":"129.585441ms","start":"2026-03-14T07:02:53.399787Z","end":"2026-03-14T07:02:53.529373Z","steps":["trace[398014925] 'range keys from in-memory index tree' (duration: 129.334306ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:53.529409Z","caller":"traceutil/trace.go:171","msg":"trace[2132478161] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1890; }","duration":"138.660395ms","start":"2026-03-14T07:02:53.390729Z","end":"2026-03-14T07:02:53.52939Z","steps":["trace[2132478161] 'range keys from in-memory index tree' (duration: 138.296796ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:53.758646Z","caller":"traceutil/trace.go:171","msg":"trace[1244071956] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"225.335686ms","start":"2026-03-14T07:02:53.533284Z","end":"2026-03-14T07:02:53.75862Z","steps":["trace[1244071956] 'process raft request' (duration: 225.156212ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:53.792288Z","caller":"traceutil/trace.go:171","msg":"trace[471164457] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"258.595491ms","start":"2026-03-14T07:02:53.533668Z","end":"2026-03-14T07:02:53.792263Z","steps":["trace[471164457] 'process raft request' (duration: 258.409717ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:53.792423Z","caller":"traceutil/trace.go:171","msg":"trace[546269505] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1963; }","duration":"116.472997ms","start":"2026-03-14T07:02:53.675929Z","end":"2026-03-14T07:02:53.792402Z","steps":["trace[546269505] 'read index received' (duration: 82.516477ms)","trace[546269505] 'applied index is now lower than readState.Index' (duration: 33.95038ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:53.792492Z","caller":"traceutil/trace.go:171","msg":"trace[2007170455] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"257.62106ms","start":"2026-03-14T07:02:53.534839Z","end":"2026-03-14T07:02:53.79246Z","steps":["trace[2007170455] 'process raft request' (duration: 257.386854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:53.792608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.653322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-14T07:02:53.792702Z","caller":"traceutil/trace.go:171","msg":"trace[1365356385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"116.757514ms","start":"2026-03-14T07:02:53.675924Z","end":"2026-03-14T07:02:53.792681Z","steps":["trace[1365356385] 'agreement among raft nodes before linearized reading' (duration: 116.54315ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:11.143054Z","caller":"traceutil/trace.go:171","msg":"trace[549434867] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"158.359873ms","start":"2026-03-14T07:04:10.984666Z","end":"2026-03-14T07:04:11.143026Z","steps":["trace[549434867] 'process raft request' (duration: 158.301282ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:11.143104Z","caller":"traceutil/trace.go:171","msg":"trace[1078338283] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"159.713083ms","start":"2026-03-14T07:04:10.983348Z","end":"2026-03-14T07:04:11.143061Z","steps":["trace[1078338283] 'process raft request' (duration: 116.475729ms)","trace[1078338283] 'compare' (duration: 43.003709ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:08:04.868299Z","caller":"traceutil/trace.go:171","msg":"trace[932714270] transaction","detail":"{read_only:false; response_revision:4217; number_of_response:1; }","duration":"131.291646ms","start":"2026-03-14T07:08:04.736981Z","end":"2026-03-14T07:08:04.868273Z","steps":["trace[932714270] 'process raft request' (duration: 64.203686ms)","trace[932714270] 'compare' (duration: 66.741672ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:08:09.0734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.762783ms","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-14T07:08:09.073505Z","caller":"traceutil/trace.go:171","msg":"trace[175850697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4373; }","duration":"182.886845ms","start":"2026-03-14T07:08:08.890593Z","end":"2026-03-14T07:08:09.07348Z","steps":["trace[175850697] 'range keys from in-memory index tree' (duration: 182.633129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:09.073618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.299192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eeacc4c5-3a4a-46f6-9e7e-9c314de4d75f\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-03-14T07:08:09.073637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.588492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:08:09.07369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.485439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-14T07:08:09.073755Z","caller":"traceutil/trace.go:171","msg":"trace[833537327] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4373; }","duration":"173.752126ms","start":"2026-03-14T07:08:08.899974Z","end":"2026-03-14T07:08:09.073726Z","steps":["trace[833537327] 'count revisions from in-memory index tree' (duration: 173.539871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:09.073805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.410162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-14T07:08:09.073851Z","caller":"traceutil/trace.go:171","msg":"trace[763107868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4373; }","duration":"185.483073ms","start":"2026-03-14T07:08:08.888355Z","end":"2026-03-14T07:08:09.073838Z","steps":["trace[763107868] 'range keys from in-memory index tree' (duration: 185.305259ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:09.073765Z","caller":"traceutil/trace.go:171","msg":"trace[1187872232] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4373; }","duration":"105.593543ms","start":"2026-03-14T07:08:08.96815Z","end":"2026-03-14T07:08:09.073744Z","steps":["trace[1187872232] 'range keys from in-memory index tree' (duration: 105.332856ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:09.073707Z","caller":"traceutil/trace.go:171","msg":"trace[974324671] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eeacc4c5-3a4a-46f6-9e7e-9c314de4d75f; range_end:; response_count:1; response_revision:4373; }","duration":"114.432485ms","start":"2026-03-14T07:08:08.959253Z","end":"2026-03-14T07:08:09.073685Z","steps":["trace[974324671] 'range keys from in-memory index tree' (duration: 114.102659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:26.133716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.456381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6995b066-36ce-4142-888f-9b9fd4d52b9e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-14T07:08:26.13386Z","caller":"traceutil/trace.go:171","msg":"trace[1174760479] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6995b066-36ce-4142-888f-9b9fd4d52b9e; range_end:; response_count:1; response_revision:4689; }","duration":"102.677896ms","start":"2026-03-14T07:08:26.031155Z","end":"2026-03-14T07:08:26.133833Z","steps":["trace[1174760479] 'range keys from in-memory index tree' (duration: 102.230556ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:26.828856Z","caller":"traceutil/trace.go:171","msg":"trace[2001575233] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"401.769469ms","start":"2026-03-14T07:08:26.427048Z","end":"2026-03-14T07:08:26.828817Z","steps":["trace[2001575233] 'process raft request' (duration: 401.483502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:26.829103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:26.427024Z","time spent":"401.950524ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:08:26.839863Z","caller":"traceutil/trace.go:171","msg":"trace[1188987070] linearizableReadLoop","detail":"{readStateIndex:4870; appliedIndex:4868; }","duration":"102.097613ms","start":"2026-03-14T07:08:26.737736Z","end":"2026-03-14T07:08:26.839833Z","steps":["trace[1188987070] 'read index received' (duration: 90.728405ms)","trace[1188987070] 'applied index is now lower than readState.Index' (duration: 11.368178ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:08:26.839896Z","caller":"traceutil/trace.go:171","msg":"trace[81642758] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"411.664754ms","start":"2026-03-14T07:08:26.428203Z","end":"2026-03-14T07:08:26.839868Z","steps":["trace[81642758] 'process raft request' (duration: 411.386548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:08:26.840052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:08:26.428189Z","time spent":"411.764998ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:08:26.840097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.336349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6995b066-36ce-4142-888f-9b9fd4d52b9e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-14T07:08:26.840136Z","caller":"traceutil/trace.go:171","msg":"trace[1997430363] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6995b066-36ce-4142-888f-9b9fd4d52b9e; range_end:; response_count:1; response_revision:4692; }","duration":"102.444882ms","start":"2026-03-14T07:08:26.737678Z","end":"2026-03-14T07:08:26.840123Z","steps":["trace[1997430363] 'agreement among raft nodes before linearized reading' (duration: 102.263427ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:08:27.024269Z","caller":"traceutil/trace.go:171","msg":"trace[2007850428] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"176.756056ms","start":"2026-03-14T07:08:26.84747Z","end":"2026-03-14T07:08:27.024226Z","steps":["trace[2007850428] 'process raft request' (duration: 164.545439ms)","trace[2007850428] 'compare' (duration: 11.951281ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:08:40.259946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.574637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:08:40.260081Z","caller":"traceutil/trace.go:171","msg":"trace[406496283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4901; }","duration":"114.798061ms","start":"2026-03-14T07:08:40.145255Z","end":"2026-03-14T07:08:40.260053Z","steps":["trace[406496283] 'range keys from in-memory index tree' (duration: 114.429153ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:10:06.104022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2585} {"level":"info","ts":"2026-03-14T07:10:06.160163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2585,"took":"54.803703ms","hash":558109542,"current-db-size-bytes":40828928,"current-db-size":"41 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-14T07:10:06.160242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":558109542,"revision":2585,"compact-revision":-1} {"level":"info","ts":"2026-03-14T07:14:25.625615Z","caller":"traceutil/trace.go:171","msg":"trace[416548278] linearizableReadLoop","detail":"{readStateIndex:7622; appliedIndex:7621; }","duration":"184.921264ms","start":"2026-03-14T07:14:25.440668Z","end":"2026-03-14T07:14:25.62559Z","steps":["trace[416548278] 'read index received' (duration: 184.724119ms)","trace[416548278] 'applied index is now lower than readState.Index' (duration: 195.925µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:25.625925Z","caller":"traceutil/trace.go:171","msg":"trace[1658301806] transaction","detail":"{read_only:false; response_revision:7357; number_of_response:1; }","duration":"186.784655ms","start":"2026-03-14T07:14:25.439129Z","end":"2026-03-14T07:14:25.625913Z","steps":["trace[1658301806] 'process raft request' (duration: 186.324494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:25.626195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.510367ms","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-14T07:14:25.62625Z","caller":"traceutil/trace.go:171","msg":"trace[897499983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7357; }","duration":"185.582798ms","start":"2026-03-14T07:14:25.440644Z","end":"2026-03-14T07:14:25.626227Z","steps":["trace[897499983] 'agreement among raft nodes before linearized reading' (duration: 185.457345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:25.626411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.200905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-03-14T07:14:25.626449Z","caller":"traceutil/trace.go:171","msg":"trace[565105127] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7357; }","duration":"182.281507ms","start":"2026-03-14T07:14:25.444156Z","end":"2026-03-14T07:14:25.626438Z","steps":["trace[565105127] 'agreement among raft nodes before linearized reading' (duration: 182.221255ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:26.565727Z","caller":"traceutil/trace.go:171","msg":"trace[1991156348] linearizableReadLoop","detail":"{readStateIndex:7625; appliedIndex:7624; }","duration":"255.157613ms","start":"2026-03-14T07:14:26.310541Z","end":"2026-03-14T07:14:26.565698Z","steps":["trace[1991156348] 'read index received' (duration: 255.04634ms)","trace[1991156348] 'applied index is now lower than readState.Index' (duration: 109.922µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:14:26.565858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.304095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:26.565895Z","caller":"traceutil/trace.go:171","msg":"trace[1531951891] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:7360; }","duration":"255.417847ms","start":"2026-03-14T07:14:26.310464Z","end":"2026-03-14T07:14:26.565882Z","steps":["trace[1531951891] 'agreement among raft nodes before linearized reading' (duration: 255.335396ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:26.565962Z","caller":"traceutil/trace.go:171","msg":"trace[1498669773] transaction","detail":"{read_only:false; response_revision:7360; number_of_response:1; }","duration":"283.98532ms","start":"2026-03-14T07:14:26.281766Z","end":"2026-03-14T07:14:26.565751Z","steps":["trace[1498669773] 'process raft request' (duration: 283.789995ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:27.321492Z","caller":"traceutil/trace.go:171","msg":"trace[244652285] linearizableReadLoop","detail":"{readStateIndex:7630; appliedIndex:7629; }","duration":"176.231085ms","start":"2026-03-14T07:14:27.145238Z","end":"2026-03-14T07:14:27.321469Z","steps":["trace[244652285] 'read index received' (duration: 176.096152ms)","trace[244652285] 'applied index is now lower than readState.Index' (duration: 133.743µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:14:27.321606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.363397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:27.321583Z","caller":"traceutil/trace.go:171","msg":"trace[1097994694] transaction","detail":"{read_only:false; response_revision:7365; number_of_response:1; }","duration":"300.11606ms","start":"2026-03-14T07:14:27.021421Z","end":"2026-03-14T07:14:27.321537Z","steps":["trace[1097994694] 'process raft request' (duration: 299.905106ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:14:27.321647Z","caller":"traceutil/trace.go:171","msg":"trace[719073658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7365; }","duration":"176.415809ms","start":"2026-03-14T07:14:27.145217Z","end":"2026-03-14T07:14:27.321633Z","steps":["trace[719073658] 'agreement among raft nodes before linearized reading' (duration: 176.342317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:27.321776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:14:27.021392Z","time spent":"300.278534ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:14:53.409243Z","caller":"traceutil/trace.go:171","msg":"trace[297532571] linearizableReadLoop","detail":"{readStateIndex:7817; appliedIndex:7816; }","duration":"283.944859ms","start":"2026-03-14T07:14:53.12528Z","end":"2026-03-14T07:14:53.409225Z","steps":["trace[297532571] 'read index received' (duration: 283.791756ms)","trace[297532571] 'applied index is now lower than readState.Index' (duration: 152.483µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:14:53.409313Z","caller":"traceutil/trace.go:171","msg":"trace[247304499] transaction","detail":"{read_only:false; response_revision:7545; number_of_response:1; }","duration":"354.228978ms","start":"2026-03-14T07:14:53.05505Z","end":"2026-03-14T07:14:53.409279Z","steps":["trace[247304499] 'process raft request' (duration: 354.068545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:53.409427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.133893ms","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-14T07:14:53.409492Z","caller":"traceutil/trace.go:171","msg":"trace[760666817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7545; }","duration":"284.213674ms","start":"2026-03-14T07:14:53.125269Z","end":"2026-03-14T07:14:53.409483Z","steps":["trace[760666817] 'agreement among raft nodes before linearized reading' (duration: 284.061341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:14:53.409489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:14:53.055041Z","time spent":"354.34911ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:14:53.409542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.543126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:14:53.409629Z","caller":"traceutil/trace.go:171","msg":"trace[757390836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7545; }","duration":"264.645949ms","start":"2026-03-14T07:14:53.144963Z","end":"2026-03-14T07:14:53.409609Z","steps":["trace[757390836] 'agreement among raft nodes before linearized reading' (duration: 264.460374ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:06.520279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5581} {"level":"warn","ts":"2026-03-14T07:15:06.521227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.366083ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-03-14T07:15:06.521334Z","caller":"traceutil/trace.go:171","msg":"trace[628435043] linearizableReadLoop","detail":"{readStateIndex:7931; appliedIndex:7930; }","duration":"316.408655ms","start":"2026-03-14T07:15:06.204909Z","end":"2026-03-14T07:15:06.521318Z","steps":["trace[628435043] 'read index received' (duration: 169.915657ms)","trace[628435043] 'applied index is now lower than readState.Index' (duration: 146.491518ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:15:06.521361Z","caller":"traceutil/trace.go:171","msg":"trace[1268597379] compact","detail":"{revision:5581; response_revision:7652; }","duration":"316.597239ms","start":"2026-03-14T07:15:06.204742Z","end":"2026-03-14T07:15:06.521339Z","steps":["trace[1268597379] 'process raft request' (duration: 170.060721ms)","trace[1268597379] 'check and update compact revision' (duration: 145.271731ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:15:06.52142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:15:06.204703Z","time spent":"316.714871ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-14T07:15:06.521484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.553468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:15:06.521547Z","caller":"traceutil/trace.go:171","msg":"trace[314883324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7652; }","duration":"316.677461ms","start":"2026-03-14T07:15:06.204849Z","end":"2026-03-14T07:15:06.521527Z","steps":["trace[314883324] 'agreement among raft nodes before linearized reading' (duration: 316.546298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:15:06.521597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:15:06.204828Z","time spent":"316.759723ms","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:15:06.522996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.782969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-03-14T07:15:06.523057Z","caller":"traceutil/trace.go:171","msg":"trace[873100777] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7653; }","duration":"148.875011ms","start":"2026-03-14T07:15:06.374169Z","end":"2026-03-14T07:15:06.523044Z","steps":["trace[873100777] 'agreement among raft nodes before linearized reading' (duration: 148.693647ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:06.523257Z","caller":"traceutil/trace.go:171","msg":"trace[130750989] transaction","detail":"{read_only:false; response_revision:7653; number_of_response:1; }","duration":"222.363729ms","start":"2026-03-14T07:15:06.300878Z","end":"2026-03-14T07:15:06.523241Z","steps":["trace[130750989] 'process raft request' (duration: 221.541341ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:06.629936Z","caller":"traceutil/trace.go:171","msg":"trace[264445005] transaction","detail":"{read_only:false; response_revision:7654; number_of_response:1; }","duration":"103.049023ms","start":"2026-03-14T07:15:06.526847Z","end":"2026-03-14T07:15:06.629896Z","steps":["trace[264445005] 'process raft request' (duration: 43.309742ms)","trace[264445005] 'compare' (duration: 18.892472ms)","trace[264445005] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/messaging-topology-operator-leader-election; req_size:553; } (duration: 40.695956ms)"],"step_count":3} {"level":"info","ts":"2026-03-14T07:15:06.791587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5581,"took":"268.774847ms","hash":4167311736,"current-db-size-bytes":42717184,"current-db-size":"43 MB","current-db-size-in-use-bytes":22372352,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-14T07:15:06.791663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4167311736,"revision":5581,"compact-revision":2585} {"level":"info","ts":"2026-03-14T07:15:07.443149Z","caller":"traceutil/trace.go:171","msg":"trace[1812821967] linearizableReadLoop","detail":"{readStateIndex:7935; appliedIndex:7934; }","duration":"344.846963ms","start":"2026-03-14T07:15:07.09828Z","end":"2026-03-14T07:15:07.443127Z","steps":["trace[1812821967] 'read index received' (duration: 344.605818ms)","trace[1812821967] 'applied index is now lower than readState.Index' (duration: 240.055µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:15:07.443199Z","caller":"traceutil/trace.go:171","msg":"trace[1018278125] transaction","detail":"{read_only:false; response_revision:7656; number_of_response:1; }","duration":"405.528674ms","start":"2026-03-14T07:15:07.037638Z","end":"2026-03-14T07:15:07.443167Z","steps":["trace[1018278125] 'process raft request' (duration: 405.33944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:15:07.44334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:15:07.037621Z","time spent":"405.642287ms","remote":"127.0.0.1:59154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5857,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:15:07.443437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.130434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:15:07.44349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.141143ms","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":"info","ts":"2026-03-14T07:15:07.443532Z","caller":"traceutil/trace.go:171","msg":"trace[1655581008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7656; }","duration":"296.264917ms","start":"2026-03-14T07:15:07.147245Z","end":"2026-03-14T07:15:07.44351Z","steps":["trace[1655581008] 'agreement among raft nodes before linearized reading' (duration: 296.087173ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:15:07.443587Z","caller":"traceutil/trace.go:171","msg":"trace[503682796] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7656; }","duration":"228.279346ms","start":"2026-03-14T07:15:07.215282Z","end":"2026-03-14T07:15:07.443562Z","steps":["trace[503682796] 'agreement among raft nodes before linearized reading' (duration: 228.101222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:15:07.443653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.252362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-14T07:15:07.443749Z","caller":"traceutil/trace.go:171","msg":"trace[1941796084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7656; }","duration":"345.506808ms","start":"2026-03-14T07:15:07.098232Z","end":"2026-03-14T07:15:07.443739Z","steps":["trace[1941796084] 'agreement among raft nodes before linearized reading' (duration: 345.039968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:15:07.443881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:15:07.098217Z","time spent":"345.555349ms","remote":"127.0.0.1:36138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-14T07:16:12.306728Z","caller":"traceutil/trace.go:171","msg":"trace[1679184514] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"132.04838ms","start":"2026-03-14T07:16:12.174647Z","end":"2026-03-14T07:16:12.306696Z","steps":["trace[1679184514] 'process raft request' (duration: 120.688896ms)","trace[1679184514] 'compare' (duration: 11.151679ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:19:28.371868Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c187bdee547e8fd1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-14T07:19:28.377421Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-14T07:19:28.377583Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-14T07:20:06.52868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7652} {"level":"info","ts":"2026-03-14T07:20:06.597476Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7652,"took":"66.463357ms","hash":3681612299,"current-db-size-bytes":42717184,"current-db-size":"43 MB","current-db-size-in-use-bytes":24920064,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-14T07:20:06.597533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3681612299,"revision":7652,"compact-revision":5581} {"level":"info","ts":"2026-03-14T07:25:06.535833Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9839} {"level":"info","ts":"2026-03-14T07:25:06.615422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9839,"took":"77.696111ms","hash":2061499681,"current-db-size-bytes":42717184,"current-db-size":"43 MB","current-db-size-in-use-bytes":16338944,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-03-14T07:25:06.615475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2061499681,"revision":9839,"compact-revision":7652}