{"level":"warn","ts":"2026-04-28T06:01:02.012372Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:01:02.012708Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T06:01:02.012782Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:01:02.012794Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-28T06:01:02.012811Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:01:02.013449Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-28T06:01:02.013523Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T06:01:02.018428Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.67559ms"} {"level":"info","ts":"2026-04-28T06:01:02.024789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-28T06:01:02.024998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:01:02.025132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-28T06:01:02.025189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:01:02.025227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-28T06:01:02.025303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-28T06:01:02.031219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:01:02.034944Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:01:02.036241Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T06:01:02.038552Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:01:02.038816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:01:02.039079Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:01:02.039217Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:01:02.039375Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:01:02.039739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-28T06:01:02.039859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-28T06:01:02.045921Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:01:02.046095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-28T06:01:02.046324Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-28T06:01:02.04615Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:01:02.046176Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:01:02.725801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:01:02.725874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:01:02.726085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-28T06:01:02.726121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:01:02.726208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-28T06:01:02.726346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-28T06:01:02.72643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-28T06:01:02.727542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:01:02.728694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:01:02.728796Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:01:02.728804Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:01:02.728827Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:01:02.728848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:01:02.729026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:01:02.729209Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:01:02.729229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:01:02.734182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:01:02.735342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-28T06:01:05.645751Z","caller":"traceutil/trace.go:171","msg":"trace[1820617376] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"111.897058ms","start":"2026-04-28T06:01:05.533809Z","end":"2026-04-28T06:01:05.645706Z","steps":["trace[1820617376] 'process raft request' (duration: 89.396279ms)","trace[1820617376] 'compare' (duration: 22.351481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:01:07.505216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.235129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-28T06:01:07.505299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.23037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:01:07.505381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.438809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:01:07.505353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.51403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:01:07.505477Z","caller":"traceutil/trace.go:171","msg":"trace[1594220385] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:222; }","duration":"286.665257ms","start":"2026-04-28T06:01:07.218764Z","end":"2026-04-28T06:01:07.50543Z","steps":["trace[1594220385] 'range keys from in-memory index tree' (duration: 286.426334ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:07.505496Z","caller":"traceutil/trace.go:171","msg":"trace[322581142] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:222; }","duration":"214.498169ms","start":"2026-04-28T06:01:07.290904Z","end":"2026-04-28T06:01:07.505402Z","steps":["trace[322581142] 'range keys from in-memory index tree' (duration: 214.39012ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:07.505397Z","caller":"traceutil/trace.go:171","msg":"trace[1561366340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:222; }","duration":"230.369726ms","start":"2026-04-28T06:01:07.275Z","end":"2026-04-28T06:01:07.50537Z","steps":["trace[1561366340] 'range keys from in-memory index tree' (duration: 230.130002ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:07.505332Z","caller":"traceutil/trace.go:171","msg":"trace[1784189126] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:222; }","duration":"213.372254ms","start":"2026-04-28T06:01:07.291941Z","end":"2026-04-28T06:01:07.505314Z","steps":["trace[1784189126] 'range keys from in-memory index tree' (duration: 213.18498ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:08.152144Z","caller":"traceutil/trace.go:171","msg":"trace[351695573] transaction","detail":"{read_only:false; number_of_response:0; response_revision:222; }","duration":"761.150702ms","start":"2026-04-28T06:01:07.390969Z","end":"2026-04-28T06:01:08.15212Z","steps":["trace[351695573] 'process raft request' (duration: 760.936523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:08.152255Z","caller":"traceutil/trace.go:171","msg":"trace[677135820] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"744.217392ms","start":"2026-04-28T06:01:07.407989Z","end":"2026-04-28T06:01:08.152206Z","steps":["trace[677135820] 'process raft request' (duration: 743.998523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:08.15229Z","caller":"traceutil/trace.go:171","msg":"trace[1899700375] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:228; }","duration":"643.80719ms","start":"2026-04-28T06:01:07.508455Z","end":"2026-04-28T06:01:08.152262Z","steps":["trace[1899700375] 'read index received' (duration: 16.269738ms)","trace[1899700375] 'applied index is now lower than readState.Index' (duration: 627.536511ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:01:08.152448Z","caller":"traceutil/trace.go:171","msg":"trace[1302198016] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"744.223412ms","start":"2026-04-28T06:01:07.408177Z","end":"2026-04-28T06:01:08.1524Z","steps":["trace[1302198016] 'process raft request' (duration: 743.893913ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:08.15262Z","caller":"traceutil/trace.go:171","msg":"trace[1472889320] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"643.077076ms","start":"2026-04-28T06:01:07.509533Z","end":"2026-04-28T06:01:08.15261Z","steps":["trace[1472889320] 'process raft request' (duration: 642.693357ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:08.152629Z","caller":"traceutil/trace.go:171","msg":"trace[777021789] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"744.336893ms","start":"2026-04-28T06:01:07.408249Z","end":"2026-04-28T06:01:08.152586Z","steps":["trace[777021789] 'process raft request' (duration: 743.857366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.152698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.390936Z","time spent":"761.303869ms","remote":"127.0.0.1:42008","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:01:08.152713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.408144Z","time spent":"744.352906ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:01:08.152782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.408234Z","time spent":"744.463866ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:01:08.1528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.407934Z","time spent":"744.416248ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:01:08.152862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.379082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-28T06:01:08.152897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.509517Z","time spent":"643.134266ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:01:08.152912Z","caller":"traceutil/trace.go:171","msg":"trace[2057302522] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:227; }","duration":"644.505005ms","start":"2026-04-28T06:01:07.508391Z","end":"2026-04-28T06:01:08.152896Z","steps":["trace[2057302522] 'agreement among raft nodes before linearized reading' (duration: 644.262082ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:08.152931Z","caller":"traceutil/trace.go:171","msg":"trace[694294049] transaction","detail":"{read_only:false; number_of_response:0; response_revision:225; }","duration":"744.552001ms","start":"2026-04-28T06:01:07.408369Z","end":"2026-04-28T06:01:08.152921Z","steps":["trace[694294049] 'process raft request' (duration: 743.760939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.152965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.508374Z","time spent":"644.582559ms","remote":"127.0.0.1:42220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-28T06:01:08.152979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.408338Z","time spent":"744.620044ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:01:08.152531Z","caller":"traceutil/trace.go:171","msg":"trace[1587282686] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"744.162501ms","start":"2026-04-28T06:01:07.408332Z","end":"2026-04-28T06:01:08.152494Z","steps":["trace[1587282686] 'process raft request' (duration: 743.860007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.153293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.408321Z","time spent":"744.93385ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:01:08.158351Z","caller":"traceutil/trace.go:171","msg":"trace[1705153430] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"632.93176ms","start":"2026-04-28T06:01:07.525406Z","end":"2026-04-28T06:01:08.158337Z","steps":["trace[1705153430] 'process raft request' (duration: 632.847305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.158426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.525392Z","time spent":"633.005113ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:01:08.158437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.079453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:01:08.158512Z","caller":"traceutil/trace.go:171","msg":"trace[1598509703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:228; }","duration":"283.190492ms","start":"2026-04-28T06:01:07.875308Z","end":"2026-04-28T06:01:08.158499Z","steps":["trace[1598509703] 'agreement among raft nodes before linearized reading' (duration: 283.054718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.158656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.81833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-28T06:01:08.158691Z","caller":"traceutil/trace.go:171","msg":"trace[820559649] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:228; }","duration":"649.958566ms","start":"2026-04-28T06:01:07.508724Z","end":"2026-04-28T06:01:08.158683Z","steps":["trace[820559649] 'agreement among raft nodes before linearized reading' (duration: 649.889613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.158711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.508711Z","time spent":"649.995813ms","remote":"127.0.0.1:42008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-28T06:01:08.15881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.586804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:01:08.158832Z","caller":"traceutil/trace.go:171","msg":"trace[1248844229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:228; }","duration":"445.614779ms","start":"2026-04-28T06:01:07.713212Z","end":"2026-04-28T06:01:08.158826Z","steps":["trace[1248844229] 'agreement among raft nodes before linearized reading' (duration: 445.576953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.158851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.713209Z","time spent":"445.637783ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:01:08.15895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.749223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:01:08.158971Z","caller":"traceutil/trace.go:171","msg":"trace[885372065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:228; }","duration":"445.78886ms","start":"2026-04-28T06:01:07.713177Z","end":"2026-04-28T06:01:08.158966Z","steps":["trace[885372065] 'agreement among raft nodes before linearized reading' (duration: 445.753414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.158991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.713162Z","time spent":"445.823797ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:01:08.159095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.982182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:01:08.159117Z","caller":"traceutil/trace.go:171","msg":"trace[1799990963] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:228; }","duration":"647.02103ms","start":"2026-04-28T06:01:07.51209Z","end":"2026-04-28T06:01:08.159111Z","steps":["trace[1799990963] 'agreement among raft nodes before linearized reading' (duration: 646.986263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:08.159134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:07.512082Z","time spent":"647.048034ms","remote":"127.0.0.1:41912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-28T06:01:19.323966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.123039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-28T06:01:19.324075Z","caller":"traceutil/trace.go:171","msg":"trace[1964983294] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:388; }","duration":"138.252979ms","start":"2026-04-28T06:01:19.185799Z","end":"2026-04-28T06:01:19.324052Z","steps":["trace[1964983294] 'agreement among raft nodes before linearized reading' (duration: 138.045067ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:33.783838Z","caller":"traceutil/trace.go:171","msg":"trace[50755824] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"120.718168ms","start":"2026-04-28T06:01:33.663098Z","end":"2026-04-28T06:01:33.783816Z","steps":["trace[50755824] 'process raft request' (duration: 120.245024ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:34.058037Z","caller":"traceutil/trace.go:171","msg":"trace[1745258709] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"197.272179ms","start":"2026-04-28T06:01:33.860745Z","end":"2026-04-28T06:01:34.058018Z","steps":["trace[1745258709] 'process raft request' (duration: 184.880592ms)","trace[1745258709] 'compare' (duration: 11.8957ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:01:34.059664Z","caller":"traceutil/trace.go:171","msg":"trace[1200150211] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"107.884519ms","start":"2026-04-28T06:01:33.951746Z","end":"2026-04-28T06:01:34.059631Z","steps":["trace[1200150211] 'process raft request' (duration: 106.651523ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:39.251213Z","caller":"traceutil/trace.go:171","msg":"trace[1445189270] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"164.29434ms","start":"2026-04-28T06:01:39.08688Z","end":"2026-04-28T06:01:39.251174Z","steps":["trace[1445189270] 'process raft request' (duration: 163.358529ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:39.438093Z","caller":"traceutil/trace.go:171","msg":"trace[1096591068] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"127.400149ms","start":"2026-04-28T06:01:39.31066Z","end":"2026-04-28T06:01:39.43806Z","steps":["trace[1096591068] 'process raft request' (duration: 115.88627ms)","trace[1096591068] 'compare' (duration: 11.309233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:01:40.119027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.37364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:01:40.119136Z","caller":"traceutil/trace.go:171","msg":"trace[472948726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"485.545732ms","start":"2026-04-28T06:01:39.63357Z","end":"2026-04-28T06:01:40.119115Z","steps":["trace[472948726] 'range keys from in-memory index tree' (duration: 485.295809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:40.119069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.314807ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:01:40.119213Z","caller":"traceutil/trace.go:171","msg":"trace[358520242] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:581; }","duration":"509.476437ms","start":"2026-04-28T06:01:39.60971Z","end":"2026-04-28T06:01:40.119187Z","steps":["trace[358520242] 'range keys from in-memory index tree' (duration: 509.295083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:40.120034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:01:39.63355Z","time spent":"486.444877ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:01:40.120327Z","caller":"traceutil/trace.go:171","msg":"trace[979255729] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"291.053224ms","start":"2026-04-28T06:01:39.829244Z","end":"2026-04-28T06:01:40.120297Z","steps":["trace[979255729] 'process raft request' (duration: 278.891262ms)","trace[979255729] 'compare' (duration: 10.806068ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:01:54.804503Z","caller":"traceutil/trace.go:171","msg":"trace[493527879] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"127.085877ms","start":"2026-04-28T06:01:54.677383Z","end":"2026-04-28T06:01:54.804469Z","steps":["trace[493527879] 'read index received' (duration: 126.85755ms)","trace[493527879] 'applied index is now lower than readState.Index' (duration: 227.537µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:01:54.804619Z","caller":"traceutil/trace.go:171","msg":"trace[1508391551] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"156.38139ms","start":"2026-04-28T06:01:54.648228Z","end":"2026-04-28T06:01:54.804609Z","steps":["trace[1508391551] 'process raft request' (duration: 156.070554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:01:54.804926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.531189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T06:01:54.804926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.812192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:01:54.804966Z","caller":"traceutil/trace.go:171","msg":"trace[1139288258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"127.611568ms","start":"2026-04-28T06:01:54.677344Z","end":"2026-04-28T06:01:54.804956Z","steps":["trace[1139288258] 'agreement among raft nodes before linearized reading' (duration: 127.464611ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:54.805004Z","caller":"traceutil/trace.go:171","msg":"trace[1233396416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"122.942888ms","start":"2026-04-28T06:01:54.682045Z","end":"2026-04-28T06:01:54.804988Z","steps":["trace[1233396416] 'agreement among raft nodes before linearized reading' (duration: 122.726052ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:55.030126Z","caller":"traceutil/trace.go:171","msg":"trace[1984215257] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"221.483963ms","start":"2026-04-28T06:01:54.80862Z","end":"2026-04-28T06:01:55.030104Z","steps":["trace[1984215257] 'process raft request' (duration: 221.326624ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:01:55.04496Z","caller":"traceutil/trace.go:171","msg":"trace[367872556] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"236.260226ms","start":"2026-04-28T06:01:54.808685Z","end":"2026-04-28T06:01:55.044945Z","steps":["trace[367872556] 'process raft request' (duration: 236.175186ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:02:27.193727Z","caller":"traceutil/trace.go:171","msg":"trace[142149096] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"242.159132ms","start":"2026-04-28T06:02:26.951541Z","end":"2026-04-28T06:02:27.1937Z","steps":["trace[142149096] 'process raft request' (duration: 241.984534ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:02:49.936869Z","caller":"traceutil/trace.go:171","msg":"trace[1184634624] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"114.153136ms","start":"2026-04-28T06:02:49.822678Z","end":"2026-04-28T06:02:49.936832Z","steps":["trace[1184634624] 'process raft request' (duration: 94.754519ms)","trace[1184634624] 'compare' (duration: 19.271824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:15.808738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.700382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:03:15.808839Z","caller":"traceutil/trace.go:171","msg":"trace[2038748021] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"232.893614ms","start":"2026-04-28T06:03:15.57593Z","end":"2026-04-28T06:03:15.808824Z","steps":["trace[2038748021] 'read index received' (duration: 50.757807ms)","trace[2038748021] 'applied index is now lower than readState.Index' (duration: 182.134577ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:15.808992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.984733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:03:15.809011Z","caller":"traceutil/trace.go:171","msg":"trace[2096256363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1820; }","duration":"233.044799ms","start":"2026-04-28T06:03:15.575961Z","end":"2026-04-28T06:03:15.809006Z","steps":["trace[2096256363] 'agreement among raft nodes before linearized reading' (duration: 232.947769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:15.809023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.074722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:03:15.809102Z","caller":"traceutil/trace.go:171","msg":"trace[1972527791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1820; }","duration":"233.201715ms","start":"2026-04-28T06:03:15.575884Z","end":"2026-04-28T06:03:15.809086Z","steps":["trace[1972527791] 'agreement among raft nodes before linearized reading' (duration: 233.000555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:15.80915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.292147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:03:15.809203Z","caller":"traceutil/trace.go:171","msg":"trace[305532267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1820; }","duration":"175.387897ms","start":"2026-04-28T06:03:15.633801Z","end":"2026-04-28T06:03:15.809189Z","steps":["trace[305532267] 'agreement among raft nodes before linearized reading' (duration: 175.303749ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:15.809665Z","caller":"traceutil/trace.go:171","msg":"trace[1260409774] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"269.767524ms","start":"2026-04-28T06:03:15.539871Z","end":"2026-04-28T06:03:15.809638Z","steps":["trace[1260409774] 'process raft request' (duration: 86.815475ms)","trace[1260409774] 'compare' (duration: 181.561759ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:03:15.960895Z","caller":"traceutil/trace.go:171","msg":"trace[168203455] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"146.776409ms","start":"2026-04-28T06:03:15.814091Z","end":"2026-04-28T06:03:15.960868Z","steps":["trace[168203455] 'process raft request' (duration: 127.930522ms)","trace[168203455] 'compare' (duration: 18.639926ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:03:15.961587Z","caller":"traceutil/trace.go:171","msg":"trace[1131104877] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"147.097062ms","start":"2026-04-28T06:03:15.814466Z","end":"2026-04-28T06:03:15.961563Z","steps":["trace[1131104877] 'process raft request' (duration: 146.349916ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:15.962677Z","caller":"traceutil/trace.go:171","msg":"trace[13966962] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"147.969389ms","start":"2026-04-28T06:03:15.814643Z","end":"2026-04-28T06:03:15.962613Z","steps":["trace[13966962] 'process raft request' (duration: 146.867849ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:15.962845Z","caller":"traceutil/trace.go:171","msg":"trace[487594629] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"147.924695ms","start":"2026-04-28T06:03:15.81489Z","end":"2026-04-28T06:03:15.962815Z","steps":["trace[487594629] 'process raft request' (duration: 147.364538ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:15.96268Z","caller":"traceutil/trace.go:171","msg":"trace[218258832] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1888; }","duration":"137.243121ms","start":"2026-04-28T06:03:15.825331Z","end":"2026-04-28T06:03:15.962574Z","steps":["trace[218258832] 'read index received' (duration: 21.706295ms)","trace[218258832] 'applied index is now lower than readState.Index' (duration: 115.534325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:15.963283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.93364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d914a0f-9f7e-4ff2-977d-11520fb08b32\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-28T06:03:15.963356Z","caller":"traceutil/trace.go:171","msg":"trace[1416534583] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d914a0f-9f7e-4ff2-977d-11520fb08b32; range_end:; response_count:1; response_revision:1825; }","duration":"138.041241ms","start":"2026-04-28T06:03:15.825299Z","end":"2026-04-28T06:03:15.96334Z","steps":["trace[1416534583] 'agreement among raft nodes before linearized reading' (duration: 137.825869ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:16.279961Z","caller":"traceutil/trace.go:171","msg":"trace[2012965794] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"306.71867ms","start":"2026-04-28T06:03:15.973201Z","end":"2026-04-28T06:03:16.27992Z","steps":["trace[2012965794] 'process raft request' (duration: 297.174471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:16.280539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:15.973183Z","time spent":"307.251554ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:03:16.292768Z","caller":"traceutil/trace.go:171","msg":"trace[1847615422] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"290.454193ms","start":"2026-04-28T06:03:16.00228Z","end":"2026-04-28T06:03:16.292734Z","steps":["trace[1847615422] 'process raft request' (duration: 290.351603ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:16.292744Z","caller":"traceutil/trace.go:171","msg":"trace[1652554895] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"301.073232ms","start":"2026-04-28T06:03:15.991629Z","end":"2026-04-28T06:03:16.292703Z","steps":["trace[1652554895] 'process raft request' (duration: 300.792604ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:16.292737Z","caller":"traceutil/trace.go:171","msg":"trace[9125718] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1894; }","duration":"171.761745ms","start":"2026-04-28T06:03:16.120932Z","end":"2026-04-28T06:03:16.292694Z","steps":["trace[9125718] 'read index received' (duration: 149.5379ms)","trace[9125718] 'applied index is now lower than readState.Index' (duration: 22.222525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:16.293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:15.991603Z","time spent":"301.288463ms","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:03:16.29296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.00576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d914a0f-9f7e-4ff2-977d-11520fb08b32\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-28T06:03:16.29379Z","caller":"traceutil/trace.go:171","msg":"trace[1941826359] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d914a0f-9f7e-4ff2-977d-11520fb08b32; range_end:; response_count:1; response_revision:1828; }","duration":"172.893909ms","start":"2026-04-28T06:03:16.120872Z","end":"2026-04-28T06:03:16.293766Z","steps":["trace[1941826359] 'agreement among raft nodes before linearized reading' (duration: 171.928022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:51.541861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.297988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:03:51.542122Z","caller":"traceutil/trace.go:171","msg":"trace[603537657] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"1.141505459s","start":"2026-04-28T06:03:50.400571Z","end":"2026-04-28T06:03:51.542077Z","steps":["trace[603537657] 'process raft request' (duration: 328.903501ms)","trace[603537657] 'compare' (duration: 811.594527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:51.542275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:50.400542Z","time spent":"1.141677815s","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:03:51.542313Z","caller":"traceutil/trace.go:171","msg":"trace[1042789362] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"1.108426241s","start":"2026-04-28T06:03:50.433816Z","end":"2026-04-28T06:03:51.542242Z","steps":["trace[1042789362] 'process raft request' (duration: 1.108162395s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:51.542714Z","caller":"traceutil/trace.go:171","msg":"trace[1792170846] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2144; }","duration":"909.803545ms","start":"2026-04-28T06:03:50.632871Z","end":"2026-04-28T06:03:51.542675Z","steps":["trace[1792170846] 'read index received' (duration: 96.555403ms)","trace[1792170846] 'applied index is now lower than readState.Index' (duration: 813.241081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:51.542842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:50.433789Z","time spent":"1.108980767s","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:03:51.662132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029228825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:03:51.662242Z","caller":"traceutil/trace.go:171","msg":"trace[576397963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2070; }","duration":"1.029420645s","start":"2026-04-28T06:03:50.632794Z","end":"2026-04-28T06:03:51.662215Z","steps":["trace[576397963] 'agreement among raft nodes before linearized reading' (duration: 910.103605ms)","trace[576397963] 'range keys from in-memory index tree' (duration: 119.131432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:51.66223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.168165ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-28T06:03:51.662338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:50.632771Z","time spent":"1.029554548s","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:03:51.662367Z","caller":"traceutil/trace.go:171","msg":"trace[102074143] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"119.552114ms","start":"2026-04-28T06:03:51.542788Z","end":"2026-04-28T06:03:51.66234Z","steps":["trace[102074143] 'read index received' (duration: 157.536µs)","trace[102074143] 'applied index is now lower than readState.Index' (duration: 119.392247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:03:51.662583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.032605ms","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-04-28T06:03:51.662653Z","caller":"traceutil/trace.go:171","msg":"trace[1376849289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2070; }","duration":"392.139345ms","start":"2026-04-28T06:03:51.270485Z","end":"2026-04-28T06:03:51.662624Z","steps":["trace[1376849289] 'agreement among raft nodes before linearized reading' (duration: 391.891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:51.662687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.320544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T06:03:51.662694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:51.270464Z","time spent":"392.217573ms","remote":"127.0.0.1:33130","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":"info","ts":"2026-04-28T06:03:51.662738Z","caller":"traceutil/trace.go:171","msg":"trace[388696920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2070; }","duration":"676.418314ms","start":"2026-04-28T06:03:50.986306Z","end":"2026-04-28T06:03:51.662724Z","steps":["trace[388696920] 'agreement among raft nodes before linearized reading' (duration: 676.098692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:51.662812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:03:50.986283Z","time spent":"676.48413ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:03:51.72378Z","caller":"traceutil/trace.go:171","msg":"trace[1373239314] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"112.634154ms","start":"2026-04-28T06:03:51.611107Z","end":"2026-04-28T06:03:51.723742Z","steps":["trace[1373239314] 'process raft request' (duration: 112.368318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:51.963634Z","caller":"traceutil/trace.go:171","msg":"trace[979087083] linearizableReadLoop","detail":"{readStateIndex:2149; appliedIndex:2148; }","duration":"239.909347ms","start":"2026-04-28T06:03:51.723703Z","end":"2026-04-28T06:03:51.963612Z","steps":["trace[979087083] 'read index received' (duration: 239.072504ms)","trace[979087083] 'applied index is now lower than readState.Index' (duration: 835.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:03:51.963647Z","caller":"traceutil/trace.go:171","msg":"trace[714488550] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"296.165383ms","start":"2026-04-28T06:03:51.667451Z","end":"2026-04-28T06:03:51.963617Z","steps":["trace[714488550] 'process raft request' (duration: 295.225019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:03:51.963927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.950702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T06:03:51.963941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.132491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:03:51.963982Z","caller":"traceutil/trace.go:171","msg":"trace[1665439893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2072; }","duration":"232.21242ms","start":"2026-04-28T06:03:51.731755Z","end":"2026-04-28T06:03:51.963968Z","steps":["trace[1665439893] 'agreement among raft nodes before linearized reading' (duration: 232.132452ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:51.963954Z","caller":"traceutil/trace.go:171","msg":"trace[377538270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2072; }","duration":"265.028069ms","start":"2026-04-28T06:03:51.698918Z","end":"2026-04-28T06:03:51.963946Z","steps":["trace[377538270] 'agreement among raft nodes before linearized reading' (duration: 264.803597ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:52.100276Z","caller":"traceutil/trace.go:171","msg":"trace[1118701131] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"130.707593ms","start":"2026-04-28T06:03:51.969522Z","end":"2026-04-28T06:03:52.100229Z","steps":["trace[1118701131] 'process raft request' (duration: 130.310504ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:03:52.101082Z","caller":"traceutil/trace.go:171","msg":"trace[714419288] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"130.564379ms","start":"2026-04-28T06:03:51.970486Z","end":"2026-04-28T06:03:52.101051Z","steps":["trace[714419288] 'process raft request' (duration: 130.336667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:54.490873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.324799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:04:54.490939Z","caller":"traceutil/trace.go:171","msg":"trace[1649419885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2512; }","duration":"126.426999ms","start":"2026-04-28T06:04:54.364498Z","end":"2026-04-28T06:04:54.490925Z","steps":["trace[1649419885] 'range keys from in-memory index tree' (duration: 125.841191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:04:54.491189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.116008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:04:54.491238Z","caller":"traceutil/trace.go:171","msg":"trace[441462493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2512; }","duration":"112.205647ms","start":"2026-04-28T06:04:54.379022Z","end":"2026-04-28T06:04:54.491227Z","steps":["trace[441462493] 'range keys from in-memory index tree' (duration: 111.361083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:28.428187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.880523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18aa702543c7a97d\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-28T06:05:28.437472Z","caller":"traceutil/trace.go:171","msg":"trace[1716436636] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18aa702543c7a97d; range_end:; response_count:1; response_revision:2696; }","duration":"131.166454ms","start":"2026-04-28T06:05:28.306222Z","end":"2026-04-28T06:05:28.437388Z","steps":["trace[1716436636] 'range keys from in-memory index tree' (duration: 121.725357ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:28.726726Z","caller":"traceutil/trace.go:171","msg":"trace[174718160] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"298.94699ms","start":"2026-04-28T06:05:28.42775Z","end":"2026-04-28T06:05:28.726697Z","steps":["trace[174718160] 'process raft request' (duration: 298.735219ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:28.72892Z","caller":"traceutil/trace.go:171","msg":"trace[1533787809] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2796; }","duration":"275.097494ms","start":"2026-04-28T06:05:28.453803Z","end":"2026-04-28T06:05:28.728901Z","steps":["trace[1533787809] 'read index received' (duration: 272.964622ms)","trace[1533787809] 'applied index is now lower than readState.Index' (duration: 2.131512ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:05:28.729172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.344078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:05:28.729069Z","caller":"traceutil/trace.go:171","msg":"trace[1287357681] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"288.964329ms","start":"2026-04-28T06:05:28.439971Z","end":"2026-04-28T06:05:28.728935Z","steps":["trace[1287357681] 'process raft request' (duration: 288.840927ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:28.729209Z","caller":"traceutil/trace.go:171","msg":"trace[126416504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2698; }","duration":"275.444828ms","start":"2026-04-28T06:05:28.453755Z","end":"2026-04-28T06:05:28.7292Z","steps":["trace[126416504] 'agreement among raft nodes before linearized reading' (duration: 275.2598ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:01.973118Z","caller":"traceutil/trace.go:171","msg":"trace[1177452319] linearizableReadLoop","detail":"{readStateIndex:4547; appliedIndex:4545; }","duration":"145.760663ms","start":"2026-04-28T06:09:01.827333Z","end":"2026-04-28T06:09:01.973094Z","steps":["trace[1177452319] 'read index received' (duration: 80.493023ms)","trace[1177452319] 'applied index is now lower than readState.Index' (duration: 65.26661ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:01.973254Z","caller":"traceutil/trace.go:171","msg":"trace[1657097610] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"155.128715ms","start":"2026-04-28T06:09:01.818113Z","end":"2026-04-28T06:09:01.973242Z","steps":["trace[1657097610] 'process raft request' (duration: 89.712686ms)","trace[1657097610] 'compare' (duration: 65.093013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:01.973367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.023304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:09:01.973422Z","caller":"traceutil/trace.go:171","msg":"trace[1890794789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4379; }","duration":"146.096228ms","start":"2026-04-28T06:09:01.827312Z","end":"2026-04-28T06:09:01.973408Z","steps":["trace[1890794789] 'agreement among raft nodes before linearized reading' (duration: 145.954629ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:01.973509Z","caller":"traceutil/trace.go:171","msg":"trace[1440534836] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"153.178318ms","start":"2026-04-28T06:09:01.820323Z","end":"2026-04-28T06:09:01.973501Z","steps":["trace[1440534836] 'process raft request' (duration: 152.730261ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:02.120886Z","caller":"traceutil/trace.go:171","msg":"trace[1748713879] transaction","detail":"{read_only:false; response_revision:4380; number_of_response:1; }","duration":"143.105931ms","start":"2026-04-28T06:09:01.977702Z","end":"2026-04-28T06:09:02.120808Z","steps":["trace[1748713879] 'process raft request' (duration: 142.959251ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:02.158381Z","caller":"traceutil/trace.go:171","msg":"trace[437250349] linearizableReadLoop","detail":"{readStateIndex:4549; appliedIndex:4547; }","duration":"132.214197ms","start":"2026-04-28T06:09:02.026149Z","end":"2026-04-28T06:09:02.158363Z","steps":["trace[437250349] 'read index received' (duration: 94.614357ms)","trace[437250349] 'applied index is now lower than readState.Index' (duration: 37.59885ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:02.158704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.543763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T06:09:02.158752Z","caller":"traceutil/trace.go:171","msg":"trace[2021377849] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4381; }","duration":"132.626087ms","start":"2026-04-28T06:09:02.026112Z","end":"2026-04-28T06:09:02.158738Z","steps":["trace[2021377849] 'agreement among raft nodes before linearized reading' (duration: 132.381064ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:02.159068Z","caller":"traceutil/trace.go:171","msg":"trace[1466513296] transaction","detail":"{read_only:false; response_revision:4381; number_of_response:1; }","duration":"180.958613ms","start":"2026-04-28T06:09:01.978096Z","end":"2026-04-28T06:09:02.159055Z","steps":["trace[1466513296] 'process raft request' (duration: 180.155041ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:05.811339Z","caller":"traceutil/trace.go:171","msg":"trace[1657355043] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"419.175437ms","start":"2026-04-28T06:09:05.392141Z","end":"2026-04-28T06:09:05.811316Z","steps":["trace[1657355043] 'process raft request' (duration: 419.023958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:05.811496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:05.392121Z","time spent":"419.293949ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7449,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:05.812301Z","caller":"traceutil/trace.go:171","msg":"trace[1933937630] linearizableReadLoop","detail":"{readStateIndex:4648; appliedIndex:4646; }","duration":"375.917553ms","start":"2026-04-28T06:09:05.436359Z","end":"2026-04-28T06:09:05.812277Z","steps":["trace[1933937630] 'read index received' (duration: 374.818999ms)","trace[1933937630] 'applied index is now lower than readState.Index' (duration: 1.051857ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:05.813929Z","caller":"traceutil/trace.go:171","msg":"trace[1721395086] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"178.287051ms","start":"2026-04-28T06:09:05.6356Z","end":"2026-04-28T06:09:05.813887Z","steps":["trace[1721395086] 'process raft request' (duration: 176.544582ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:05.813946Z","caller":"traceutil/trace.go:171","msg":"trace[272851535] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"391.251711ms","start":"2026-04-28T06:09:05.422671Z","end":"2026-04-28T06:09:05.813922Z","steps":["trace[272851535] 'process raft request' (duration: 389.341873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:05.814159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:05.422652Z","time spent":"391.390312ms","remote":"127.0.0.1:49448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:05.814265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.888667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:09:05.814489Z","caller":"traceutil/trace.go:171","msg":"trace[1696599339] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4481; }","duration":"378.120541ms","start":"2026-04-28T06:09:05.436326Z","end":"2026-04-28T06:09:05.814447Z","steps":["trace[1696599339] 'agreement among raft nodes before linearized reading' (duration: 377.866328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:05.814535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:05.436311Z","time spent":"378.213204ms","remote":"127.0.0.1:42128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:05.814815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.272477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:05.814969Z","caller":"traceutil/trace.go:171","msg":"trace[1444678438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4481; }","duration":"361.437746ms","start":"2026-04-28T06:09:05.453512Z","end":"2026-04-28T06:09:05.81495Z","steps":["trace[1444678438] 'agreement among raft nodes before linearized reading' (duration: 361.244099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:05.81502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:05.453448Z","time spent":"361.557128ms","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:05.81526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.544706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:09:05.815299Z","caller":"traceutil/trace.go:171","msg":"trace[331434676] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4481; }","duration":"262.615521ms","start":"2026-04-28T06:09:05.552673Z","end":"2026-04-28T06:09:05.815289Z","steps":["trace[331434676] 'agreement among raft nodes before linearized reading' (duration: 262.532117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:05.815838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.722036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194894"} {"level":"info","ts":"2026-04-28T06:09:05.815927Z","caller":"traceutil/trace.go:171","msg":"trace[1676540758] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4481; }","duration":"116.8174ms","start":"2026-04-28T06:09:05.699087Z","end":"2026-04-28T06:09:05.815905Z","steps":["trace[1676540758] 'agreement among raft nodes before linearized reading' (duration: 115.689748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:06.448059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.652244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:09:06.448139Z","caller":"traceutil/trace.go:171","msg":"trace[1349177819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4492; }","duration":"179.795554ms","start":"2026-04-28T06:09:06.268329Z","end":"2026-04-28T06:09:06.448125Z","steps":["trace[1349177819] 'range keys from in-memory index tree' (duration: 179.354215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:06.44827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.508833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T06:09:06.448366Z","caller":"traceutil/trace.go:171","msg":"trace[1459116989] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4492; }","duration":"173.632635ms","start":"2026-04-28T06:09:06.2747Z","end":"2026-04-28T06:09:06.448332Z","steps":["trace[1459116989] 'range keys from in-memory index tree' (duration: 173.347944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:07.108601Z","caller":"traceutil/trace.go:171","msg":"trace[550634364] linearizableReadLoop","detail":"{readStateIndex:4664; appliedIndex:4663; }","duration":"475.32721ms","start":"2026-04-28T06:09:06.63325Z","end":"2026-04-28T06:09:07.108577Z","steps":["trace[550634364] 'read index received' (duration: 475.207928ms)","trace[550634364] 'applied index is now lower than readState.Index' (duration: 116.803µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:07.108734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.464772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:07.108769Z","caller":"traceutil/trace.go:171","msg":"trace[1447311907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4495; }","duration":"475.557055ms","start":"2026-04-28T06:09:06.6332Z","end":"2026-04-28T06:09:07.108757Z","steps":["trace[1447311907] 'agreement among raft nodes before linearized reading' (duration: 475.48252ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:07.108721Z","caller":"traceutil/trace.go:171","msg":"trace[1016994003] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"586.676679ms","start":"2026-04-28T06:09:06.521968Z","end":"2026-04-28T06:09:07.108644Z","steps":["trace[1016994003] 'process raft request' (duration: 586.412397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:07.108807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:06.633176Z","time spent":"475.621931ms","remote":"127.0.0.1:41810","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-04-28T06:09:07.108944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:06.521936Z","time spent":"586.880675ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3875,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:07.113584Z","caller":"traceutil/trace.go:171","msg":"trace[1724525998] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"262.620798ms","start":"2026-04-28T06:09:06.850949Z","end":"2026-04-28T06:09:07.11357Z","steps":["trace[1724525998] 'process raft request' (duration: 262.207935ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:07.113737Z","caller":"traceutil/trace.go:171","msg":"trace[1049557510] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"108.284043ms","start":"2026-04-28T06:09:07.005443Z","end":"2026-04-28T06:09:07.113728Z","steps":["trace[1049557510] 'process raft request' (duration: 107.849832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:07.113882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.792799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-04-28T06:09:07.113916Z","caller":"traceutil/trace.go:171","msg":"trace[306955054] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395; range_end:; response_count:1; response_revision:4497; }","duration":"177.826037ms","start":"2026-04-28T06:09:06.936079Z","end":"2026-04-28T06:09:07.113905Z","steps":["trace[306955054] 'agreement among raft nodes before linearized reading' (duration: 177.711635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:07.113915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.514428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-04-28T06:09:07.113996Z","caller":"traceutil/trace.go:171","msg":"trace[213078670] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79; range_end:; response_count:1; response_revision:4497; }","duration":"293.654349ms","start":"2026-04-28T06:09:06.820323Z","end":"2026-04-28T06:09:07.113978Z","steps":["trace[213078670] 'agreement among raft nodes before linearized reading' (duration: 293.501219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.142248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.234772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:09.14232Z","caller":"traceutil/trace.go:171","msg":"trace[839618149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4514; }","duration":"509.345764ms","start":"2026-04-28T06:09:08.632958Z","end":"2026-04-28T06:09:09.142304Z","steps":["trace[839618149] 'range keys from in-memory index tree' (duration: 509.149397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.142408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:08.63294Z","time spent":"509.40401ms","remote":"127.0.0.1:41796","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-04-28T06:09:09.42777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.223389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:09.42785Z","caller":"traceutil/trace.go:171","msg":"trace[2144177662] linearizableReadLoop","detail":"{readStateIndex:4685; appliedIndex:4683; }","duration":"305.048334ms","start":"2026-04-28T06:09:09.122789Z","end":"2026-04-28T06:09:09.427837Z","steps":["trace[2144177662] 'read index received' (duration: 53.033873ms)","trace[2144177662] 'applied index is now lower than readState.Index' (duration: 252.013501ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:09.428015Z","caller":"traceutil/trace.go:171","msg":"trace[961366759] transaction","detail":"{read_only:false; response_revision:4516; number_of_response:1; }","duration":"307.180844ms","start":"2026-04-28T06:09:09.120825Z","end":"2026-04-28T06:09:09.428006Z","steps":["trace[961366759] 'process raft request' (duration: 175.657524ms)","trace[961366759] 'compare' (duration: 130.943408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:09.428067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:09.120818Z","time spent":"307.224432ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:09.428278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.487915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79\" ","response":"range_response_count:1 size:4076"} {"level":"info","ts":"2026-04-28T06:09:09.428307Z","caller":"traceutil/trace.go:171","msg":"trace[800801512] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79; range_end:; response_count:1; response_revision:4516; }","duration":"305.541182ms","start":"2026-04-28T06:09:09.122758Z","end":"2026-04-28T06:09:09.428299Z","steps":["trace[800801512] 'agreement among raft nodes before linearized reading' (duration: 305.446008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.428328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:09.122744Z","time spent":"305.579279ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4100,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79\" "} {"level":"warn","ts":"2026-04-28T06:09:09.428498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.338904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-04-28T06:09:09.428524Z","caller":"traceutil/trace.go:171","msg":"trace[1025454422] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395; range_end:; response_count:1; response_revision:4516; }","duration":"305.391352ms","start":"2026-04-28T06:09:09.123127Z","end":"2026-04-28T06:09:09.428518Z","steps":["trace[1025454422] 'agreement among raft nodes before linearized reading' (duration: 305.328786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.428545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:09.123113Z","time spent":"305.426209ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4086,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395\" "} {"level":"warn","ts":"2026-04-28T06:09:09.428745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.761321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:09:09.428776Z","caller":"traceutil/trace.go:171","msg":"trace[1709633943] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4516; }","duration":"269.819937ms","start":"2026-04-28T06:09:09.158949Z","end":"2026-04-28T06:09:09.428769Z","steps":["trace[1709633943] 'agreement among raft nodes before linearized reading' (duration: 269.750782ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:09.954989Z","caller":"traceutil/trace.go:171","msg":"trace[1645647835] linearizableReadLoop","detail":"{readStateIndex:4686; appliedIndex:4685; }","duration":"105.771664ms","start":"2026-04-28T06:09:09.849193Z","end":"2026-04-28T06:09:09.954965Z","steps":["trace[1645647835] 'read index received' (duration: 105.51939ms)","trace[1645647835] 'applied index is now lower than readState.Index' (duration: 251.234µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:09.955131Z","caller":"traceutil/trace.go:171","msg":"trace[215845295] transaction","detail":"{read_only:false; response_revision:4517; number_of_response:1; }","duration":"115.988436ms","start":"2026-04-28T06:09:09.839102Z","end":"2026-04-28T06:09:09.955091Z","steps":["trace[215845295] 'process raft request' (duration: 115.693035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:09.955179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:09:09.955251Z","caller":"traceutil/trace.go:171","msg":"trace[288233698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4517; }","duration":"106.094222ms","start":"2026-04-28T06:09:09.849139Z","end":"2026-04-28T06:09:09.955234Z","steps":["trace[288233698] 'agreement among raft nodes before linearized reading' (duration: 105.924503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:11.821958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.472778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:11.822032Z","caller":"traceutil/trace.go:171","msg":"trace[1452357073] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"335.962513ms","start":"2026-04-28T06:09:11.486057Z","end":"2026-04-28T06:09:11.822019Z","steps":["trace[1452357073] 'process raft request' (duration: 179.363233ms)","trace[1452357073] 'compare' (duration: 156.358115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:11.822061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.486031Z","time spent":"336.019469ms","remote":"127.0.0.1:41974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1316,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:11.843944Z","caller":"traceutil/trace.go:171","msg":"trace[376460791] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"355.719807ms","start":"2026-04-28T06:09:11.4882Z","end":"2026-04-28T06:09:11.84392Z","steps":["trace[376460791] 'process raft request' (duration: 355.615684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:11.844085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.48818Z","time spent":"355.83808ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:11.844248Z","caller":"traceutil/trace.go:171","msg":"trace[1444042919] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"327.067469ms","start":"2026-04-28T06:09:11.517149Z","end":"2026-04-28T06:09:11.844216Z","steps":["trace[1444042919] 'process raft request' (duration: 326.73284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:11.844391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.517138Z","time spent":"327.19061ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:11.844492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.237804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-74645cf88f-4rfxj\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-04-28T06:09:11.844552Z","caller":"traceutil/trace.go:171","msg":"trace[1864364773] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-74645cf88f-4rfxj; range_end:; response_count:1; response_revision:4541; }","duration":"328.338347ms","start":"2026-04-28T06:09:11.5162Z","end":"2026-04-28T06:09:11.844538Z","steps":["trace[1864364773] 'agreement among raft nodes before linearized reading' (duration: 328.097032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:11.844594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.51619Z","time spent":"328.396524ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11280,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-74645cf88f-4rfxj\" "} {"level":"info","ts":"2026-04-28T06:09:11.844228Z","caller":"traceutil/trace.go:171","msg":"trace[1938668068] linearizableReadLoop","detail":"{readStateIndex:4710; appliedIndex:4707; }","duration":"327.952892ms","start":"2026-04-28T06:09:11.516238Z","end":"2026-04-28T06:09:11.844191Z","steps":["trace[1938668068] 'read index received' (duration: 149.267266ms)","trace[1938668068] 'applied index is now lower than readState.Index' (duration: 178.683386ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:11.844647Z","caller":"traceutil/trace.go:171","msg":"trace[1278058998] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"357.305256ms","start":"2026-04-28T06:09:11.486679Z","end":"2026-04-28T06:09:11.843984Z","steps":["trace[1278058998] 'process raft request' (duration: 356.995206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:11.844891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:11.486658Z","time spent":"358.087027ms","remote":"127.0.0.1:41974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1435,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:11.845005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.857532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:11.845103Z","caller":"traceutil/trace.go:171","msg":"trace[2063626183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4541; }","duration":"211.090418ms","start":"2026-04-28T06:09:11.633991Z","end":"2026-04-28T06:09:11.845081Z","steps":["trace[2063626183] 'agreement among raft nodes before linearized reading' (duration: 210.823735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:14.991523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.658247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:14.992002Z","caller":"traceutil/trace.go:171","msg":"trace[245985308] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"277.496627ms","start":"2026-04-28T06:09:14.714473Z","end":"2026-04-28T06:09:14.991969Z","steps":["trace[245985308] 'process raft request' (duration: 79.273914ms)","trace[245985308] 'compare' (duration: 197.484888ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:15.160789Z","caller":"traceutil/trace.go:171","msg":"trace[1818755391] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"150.977469ms","start":"2026-04-28T06:09:15.009783Z","end":"2026-04-28T06:09:15.16076Z","steps":["trace[1818755391] 'process raft request' (duration: 97.342678ms)","trace[1818755391] 'compare' (duration: 53.376607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:15.854521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.26537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:15.854845Z","caller":"traceutil/trace.go:171","msg":"trace[1306476264] linearizableReadLoop","detail":"{readStateIndex:4799; appliedIndex:4797; }","duration":"469.413209ms","start":"2026-04-28T06:09:15.385413Z","end":"2026-04-28T06:09:15.854826Z","steps":["trace[1306476264] 'read index received' (duration: 82.883546ms)","trace[1306476264] 'applied index is now lower than readState.Index' (duration: 386.528633ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:15.855043Z","caller":"traceutil/trace.go:171","msg":"trace[1241713256] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"469.665824ms","start":"2026-04-28T06:09:15.38536Z","end":"2026-04-28T06:09:15.855026Z","steps":["trace[1241713256] 'process raft request' (duration: 82.81308ms)","trace[1241713256] 'compare' (duration: 385.577951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:15.855108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.385318Z","time spent":"469.762469ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:15.855146Z","caller":"traceutil/trace.go:171","msg":"trace[608968760] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"468.746339ms","start":"2026-04-28T06:09:15.386329Z","end":"2026-04-28T06:09:15.855076Z","steps":["trace[608968760] 'process raft request' (duration: 468.39784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:15.855349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.386304Z","time spent":"468.953687ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:09:15.855509Z","caller":"traceutil/trace.go:171","msg":"trace[68912780] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"470.086689ms","start":"2026-04-28T06:09:15.385405Z","end":"2026-04-28T06:09:15.855492Z","steps":["trace[68912780] 'process raft request' (duration: 469.24581ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:15.855509Z","caller":"traceutil/trace.go:171","msg":"trace[1888103427] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"393.937509ms","start":"2026-04-28T06:09:15.461558Z","end":"2026-04-28T06:09:15.855495Z","steps":["trace[1888103427] 'process raft request' (duration: 393.212392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:15.855582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.385389Z","time spent":"470.163055ms","remote":"127.0.0.1:42000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:15.855636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.461513Z","time spent":"394.046792ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:15.855979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.57288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130159"} {"level":"info","ts":"2026-04-28T06:09:15.85602Z","caller":"traceutil/trace.go:171","msg":"trace[475663698] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4629; }","duration":"470.668484ms","start":"2026-04-28T06:09:15.385341Z","end":"2026-04-28T06:09:15.856009Z","steps":["trace[475663698] 'agreement among raft nodes before linearized reading' (duration: 470.442988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:15.856051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.385311Z","time spent":"470.73332ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130183,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-28T06:09:15.863078Z","caller":"traceutil/trace.go:171","msg":"trace[1953240221] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"308.553543ms","start":"2026-04-28T06:09:15.5545Z","end":"2026-04-28T06:09:15.863054Z","steps":["trace[1953240221] 'process raft request' (duration: 308.431531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:15.863327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.554489Z","time spent":"308.7669ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:15.86345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.667537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:15.863357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.122168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-04-28T06:09:15.863506Z","caller":"traceutil/trace.go:171","msg":"trace[1901170870] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4630; }","duration":"316.362324ms","start":"2026-04-28T06:09:15.547131Z","end":"2026-04-28T06:09:15.863493Z","steps":["trace[1901170870] 'agreement among raft nodes before linearized reading' (duration: 316.015765ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:15.863497Z","caller":"traceutil/trace.go:171","msg":"trace[102276131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4630; }","duration":"230.724394ms","start":"2026-04-28T06:09:15.632758Z","end":"2026-04-28T06:09:15.863482Z","steps":["trace[102276131] 'agreement among raft nodes before linearized reading' (duration: 230.643378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:15.863551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.547108Z","time spent":"316.43214ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16255,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-28T06:09:15.863668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.681646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-28T06:09:15.863768Z","caller":"traceutil/trace.go:171","msg":"trace[1555258413] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4630; }","duration":"285.816498ms","start":"2026-04-28T06:09:15.577926Z","end":"2026-04-28T06:09:15.863743Z","steps":["trace[1555258413] 'agreement among raft nodes before linearized reading' (duration: 285.508386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.321922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.035221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:16.322156Z","caller":"traceutil/trace.go:171","msg":"trace[1578349024] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"462.398569ms","start":"2026-04-28T06:09:15.859696Z","end":"2026-04-28T06:09:16.322095Z","steps":["trace[1578349024] 'process raft request' (duration: 266.064745ms)","trace[1578349024] 'compare' (duration: 195.750338ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:16.322316Z","caller":"traceutil/trace.go:171","msg":"trace[693162030] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"415.369171ms","start":"2026-04-28T06:09:15.906921Z","end":"2026-04-28T06:09:16.32229Z","steps":["trace[693162030] 'process raft request' (duration: 415.264557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.322447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.906892Z","time spent":"415.514822ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:16.322472Z","caller":"traceutil/trace.go:171","msg":"trace[758783831] linearizableReadLoop","detail":"{readStateIndex:4803; appliedIndex:4802; }","duration":"457.827694ms","start":"2026-04-28T06:09:15.864591Z","end":"2026-04-28T06:09:16.322419Z","steps":["trace[758783831] 'read index received' (duration: 253.571249ms)","trace[758783831] 'applied index is now lower than readState.Index' (duration: 204.253345ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:16.322476Z","caller":"traceutil/trace.go:171","msg":"trace[1319465310] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"446.475014ms","start":"2026-04-28T06:09:15.87583Z","end":"2026-04-28T06:09:16.322305Z","steps":["trace[1319465310] 'process raft request' (duration: 446.258337ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:16.322584Z","caller":"traceutil/trace.go:171","msg":"trace[314782729] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"340.295108ms","start":"2026-04-28T06:09:15.982239Z","end":"2026-04-28T06:09:16.322534Z","steps":["trace[314782729] 'process raft request' (duration: 340.002755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.322634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.023842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:16.322701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.875794Z","time spent":"446.780966ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:16.322732Z","caller":"traceutil/trace.go:171","msg":"trace[1060707404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4634; }","duration":"458.180783ms","start":"2026-04-28T06:09:15.864537Z","end":"2026-04-28T06:09:16.322718Z","steps":["trace[1060707404] 'agreement among raft nodes before linearized reading' (duration: 458.002504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.322776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.864494Z","time spent":"458.263288ms","remote":"127.0.0.1:41796","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-04-28T06:09:16.322798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.859664Z","time spent":"462.581408ms","remote":"127.0.0.1:41860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":130145,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:16.32282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.311545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:09:16.322866Z","caller":"traceutil/trace.go:171","msg":"trace[2053634786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4634; }","duration":"231.37304ms","start":"2026-04-28T06:09:16.091478Z","end":"2026-04-28T06:09:16.322851Z","steps":["trace[2053634786] 'agreement among raft nodes before linearized reading' (duration: 231.243318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.322849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.126036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-28T06:09:16.322957Z","caller":"traceutil/trace.go:171","msg":"trace[889279691] range","detail":"{range_begin:/registry/persistentvolumes/pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79; range_end:; response_count:1; response_revision:4634; }","duration":"456.358672ms","start":"2026-04-28T06:09:15.866572Z","end":"2026-04-28T06:09:16.322931Z","steps":["trace[889279691] 'agreement among raft nodes before linearized reading' (duration: 456.030382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.323049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.961678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:16.323059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.68052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T06:09:16.322802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.982215Z","time spent":"340.462867ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:16.323096Z","caller":"traceutil/trace.go:171","msg":"trace[102352778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4634; }","duration":"120.767115ms","start":"2026-04-28T06:09:16.202322Z","end":"2026-04-28T06:09:16.323089Z","steps":["trace[102352778] 'agreement among raft nodes before linearized reading' (duration: 120.696249ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:16.3231Z","caller":"traceutil/trace.go:171","msg":"trace[494091613] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4634; }","duration":"325.059732ms","start":"2026-04-28T06:09:15.998023Z","end":"2026-04-28T06:09:16.323083Z","steps":["trace[494091613] 'agreement among raft nodes before linearized reading' (duration: 324.959178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.323132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.445129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:09:16.323159Z","caller":"traceutil/trace.go:171","msg":"trace[213067015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4634; }","duration":"326.506625ms","start":"2026-04-28T06:09:15.996643Z","end":"2026-04-28T06:09:16.323149Z","steps":["trace[213067015] 'agreement among raft nodes before linearized reading' (duration: 326.43111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.323188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.840962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-28T06:09:16.323204Z","caller":"traceutil/trace.go:171","msg":"trace[1115613783] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4634; }","duration":"278.898159ms","start":"2026-04-28T06:09:16.0443Z","end":"2026-04-28T06:09:16.323198Z","steps":["trace[1115613783] 'agreement among raft nodes before linearized reading' (duration: 278.843302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.323219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.997998Z","time spent":"325.137367ms","remote":"127.0.0.1:49460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:16.323288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.349068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:09:16.323341Z","caller":"traceutil/trace.go:171","msg":"trace[1141796450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4634; }","duration":"226.419674ms","start":"2026-04-28T06:09:16.096905Z","end":"2026-04-28T06:09:16.323325Z","steps":["trace[1141796450] 'agreement among raft nodes before linearized reading' (duration: 226.231595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.3234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.334336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T06:09:16.323458Z","caller":"traceutil/trace.go:171","msg":"trace[906382798] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:4634; }","duration":"345.435359ms","start":"2026-04-28T06:09:15.978006Z","end":"2026-04-28T06:09:16.323441Z","steps":["trace[906382798] 'agreement among raft nodes before linearized reading' (duration: 345.202023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:16.323492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.977986Z","time spent":"345.497676ms","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-04-28T06:09:16.32451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.866542Z","time spent":"457.947898ms","remote":"127.0.0.1:41974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1474,"request content":"key:\"/registry/persistentvolumes/pvc-241ec9eb-c1f5-49f1-96c4-3a8367d98b79\" "} {"level":"warn","ts":"2026-04-28T06:09:16.323183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:15.996624Z","time spent":"326.552653ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:09:18.923685Z","caller":"traceutil/trace.go:171","msg":"trace[2074647621] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4900; }","duration":"316.326108ms","start":"2026-04-28T06:09:18.607342Z","end":"2026-04-28T06:09:18.923668Z","steps":["trace[2074647621] 'read index received' (duration: 316.169847ms)","trace[2074647621] 'applied index is now lower than readState.Index' (duration: 155.681µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:18.923896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.393327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk.18aa705f11c24814\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-28T06:09:18.923927Z","caller":"traceutil/trace.go:171","msg":"trace[487820862] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk.18aa705f11c24814; range_end:; response_count:1; response_revision:4728; }","duration":"313.453053ms","start":"2026-04-28T06:09:18.610465Z","end":"2026-04-28T06:09:18.923918Z","steps":["trace[487820862] 'agreement among raft nodes before linearized reading' (duration: 313.300572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:18.923953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:18.61046Z","time spent":"313.486471ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":986,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk.18aa705f11c24814\" "} {"level":"warn","ts":"2026-04-28T06:09:18.923939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.015087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk-tls\" ","response":"range_response_count:1 size:1966"} {"level":"warn","ts":"2026-04-28T06:09:18.923965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.592412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T06:09:18.924063Z","caller":"traceutil/trace.go:171","msg":"trace[1758890394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4728; }","duration":"316.731585ms","start":"2026-04-28T06:09:18.607305Z","end":"2026-04-28T06:09:18.924037Z","steps":["trace[1758890394] 'agreement among raft nodes before linearized reading' (duration: 316.46718ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:18.924005Z","caller":"traceutil/trace.go:171","msg":"trace[828190838] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk-tls; range_end:; response_count:1; response_revision:4728; }","duration":"232.113281ms","start":"2026-04-28T06:09:18.691873Z","end":"2026-04-28T06:09:18.923986Z","steps":["trace[828190838] 'agreement among raft nodes before linearized reading' (duration: 231.95359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:18.92412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:18.60729Z","time spent":"316.81878ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T06:09:18.924197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.373085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:18.924296Z","caller":"traceutil/trace.go:171","msg":"trace[1743816673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4728; }","duration":"292.543405ms","start":"2026-04-28T06:09:18.63173Z","end":"2026-04-28T06:09:18.924274Z","steps":["trace[1743816673] 'agreement among raft nodes before linearized reading' (duration: 292.303029ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:18.924255Z","caller":"traceutil/trace.go:171","msg":"trace[1922096617] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"391.522923ms","start":"2026-04-28T06:09:18.53217Z","end":"2026-04-28T06:09:18.923693Z","steps":["trace[1922096617] 'process raft request' (duration: 391.386541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:18.924719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:18.532148Z","time spent":"392.472468ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:19.127097Z","caller":"traceutil/trace.go:171","msg":"trace[1039924030] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"200.240083ms","start":"2026-04-28T06:09:18.926756Z","end":"2026-04-28T06:09:19.126996Z","steps":["trace[1039924030] 'process raft request' (duration: 200.064173ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:19.127481Z","caller":"traceutil/trace.go:171","msg":"trace[907338906] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4901; }","duration":"196.3116ms","start":"2026-04-28T06:09:18.931148Z","end":"2026-04-28T06:09:19.12746Z","steps":["trace[907338906] 'read index received' (duration: 195.643288ms)","trace[907338906] 'applied index is now lower than readState.Index' (duration: 666.682µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:19.127576Z","caller":"traceutil/trace.go:171","msg":"trace[925983067] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"198.794167ms","start":"2026-04-28T06:09:18.928745Z","end":"2026-04-28T06:09:19.12754Z","steps":["trace[925983067] 'process raft request' (duration: 198.604208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:19.127717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.543387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk-tls\" ","response":"range_response_count:1 size:4431"} {"level":"info","ts":"2026-04-28T06:09:19.1278Z","caller":"traceutil/trace.go:171","msg":"trace[890970776] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk-tls; range_end:; response_count:1; response_revision:4730; }","duration":"196.641931ms","start":"2026-04-28T06:09:18.931123Z","end":"2026-04-28T06:09:19.127765Z","steps":["trace[890970776] 'agreement among raft nodes before linearized reading' (duration: 196.411694ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:23.984192Z","caller":"traceutil/trace.go:171","msg":"trace[661095009] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"106.839874ms","start":"2026-04-28T06:09:23.877332Z","end":"2026-04-28T06:09:23.984172Z","steps":["trace[661095009] 'process raft request' (duration: 99.804311ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:24.531185Z","caller":"traceutil/trace.go:171","msg":"trace[1375038304] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"141.755829ms","start":"2026-04-28T06:09:24.389405Z","end":"2026-04-28T06:09:24.531161Z","steps":["trace[1375038304] 'read index received' (duration: 137.466726ms)","trace[1375038304] 'applied index is now lower than readState.Index' (duration: 4.288023ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:24.531227Z","caller":"traceutil/trace.go:171","msg":"trace[1506000525] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"146.549146ms","start":"2026-04-28T06:09:24.384641Z","end":"2026-04-28T06:09:24.53119Z","steps":["trace[1506000525] 'process raft request' (duration: 142.222084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:24.531382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.710035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-28T06:09:24.531394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.581568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:09:24.531421Z","caller":"traceutil/trace.go:171","msg":"trace[1542633593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4812; }","duration":"136.766422ms","start":"2026-04-28T06:09:24.394645Z","end":"2026-04-28T06:09:24.531411Z","steps":["trace[1542633593] 'agreement among raft nodes before linearized reading' (duration: 136.656128ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:24.531425Z","caller":"traceutil/trace.go:171","msg":"trace[1396628495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4812; }","duration":"141.658644ms","start":"2026-04-28T06:09:24.389757Z","end":"2026-04-28T06:09:24.531416Z","steps":["trace[1396628495] 'agreement among raft nodes before linearized reading' (duration: 141.53452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:24.531409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.988346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:09:24.531467Z","caller":"traceutil/trace.go:171","msg":"trace[1156856096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4812; }","duration":"142.097971ms","start":"2026-04-28T06:09:24.389357Z","end":"2026-04-28T06:09:24.531455Z","steps":["trace[1156856096] 'agreement among raft nodes before linearized reading' (duration: 141.889142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.871467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.837132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:28.871755Z","caller":"traceutil/trace.go:171","msg":"trace[2119399510] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"481.857569ms","start":"2026-04-28T06:09:28.38984Z","end":"2026-04-28T06:09:28.871697Z","steps":["trace[2119399510] 'process raft request' (duration: 481.786253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.871928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.389813Z","time spent":"482.075619ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:28.871798Z","caller":"traceutil/trace.go:171","msg":"trace[1550627912] linearizableReadLoop","detail":"{readStateIndex:5024; appliedIndex:5023; }","duration":"830.593292ms","start":"2026-04-28T06:09:28.041168Z","end":"2026-04-28T06:09:28.871762Z","steps":["trace[1550627912] 'read index received' (duration: 304.331021ms)","trace[1550627912] 'applied index is now lower than readState.Index' (duration: 526.259161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:28.872022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.82657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-74645cf88f-4rfxj\" ","response":"range_response_count:1 size:11844"} {"level":"info","ts":"2026-04-28T06:09:28.872068Z","caller":"traceutil/trace.go:171","msg":"trace[1258305144] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-74645cf88f-4rfxj; range_end:; response_count:1; response_revision:4847; }","duration":"830.926375ms","start":"2026-04-28T06:09:28.041131Z","end":"2026-04-28T06:09:28.872057Z","steps":["trace[1258305144] 'agreement among raft nodes before linearized reading' (duration: 830.718056ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:28.871721Z","caller":"traceutil/trace.go:171","msg":"trace[456136852] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"877.631724ms","start":"2026-04-28T06:09:27.99405Z","end":"2026-04-28T06:09:28.871682Z","steps":["trace[456136852] 'process raft request' (duration: 351.460609ms)","trace[456136852] 'compare' (duration: 523.737767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:28.872106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.041122Z","time spent":"830.976072ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11868,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-74645cf88f-4rfxj\" "} {"level":"warn","ts":"2026-04-28T06:09:28.872161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:27.994024Z","time spent":"878.073242ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:28.892059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.576338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:28.892175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.253646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-28T06:09:28.892182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.9735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:09:28.892223Z","caller":"traceutil/trace.go:171","msg":"trace[1027080434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4848; }","duration":"258.751859ms","start":"2026-04-28T06:09:28.633434Z","end":"2026-04-28T06:09:28.892186Z","steps":["trace[1027080434] 'agreement among raft nodes before linearized reading' (duration: 257.660834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.892312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.724663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:28.892204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.074105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:09:28.892353Z","caller":"traceutil/trace.go:171","msg":"trace[1361814735] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4848; }","duration":"735.79364ms","start":"2026-04-28T06:09:28.15654Z","end":"2026-04-28T06:09:28.892334Z","steps":["trace[1361814735] 'agreement among raft nodes before linearized reading' (duration: 735.722553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.892381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.156521Z","time spent":"735.853037ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:28.89239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.088904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:09:28.892235Z","caller":"traceutil/trace.go:171","msg":"trace[1893921725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4848; }","duration":"342.042507ms","start":"2026-04-28T06:09:28.55017Z","end":"2026-04-28T06:09:28.892213Z","steps":["trace[1893921725] 'agreement among raft nodes before linearized reading' (duration: 341.915493ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:28.89246Z","caller":"traceutil/trace.go:171","msg":"trace[826687858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4848; }","duration":"345.286544ms","start":"2026-04-28T06:09:28.547163Z","end":"2026-04-28T06:09:28.892449Z","steps":["trace[826687858] 'agreement among raft nodes before linearized reading' (duration: 345.097063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.892511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.547137Z","time spent":"345.36491ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:28.892518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.550155Z","time spent":"342.333862ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:28.892311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.841485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-28T06:09:28.892523Z","caller":"traceutil/trace.go:171","msg":"trace[1646623930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4848; }","duration":"345.314573ms","start":"2026-04-28T06:09:28.547077Z","end":"2026-04-28T06:09:28.892392Z","steps":["trace[1646623930] 'agreement among raft nodes before linearized reading' (duration: 344.955071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.892748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.547064Z","time spent":"345.666855ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:09:28.892237Z","caller":"traceutil/trace.go:171","msg":"trace[952462447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4848; }","duration":"349.335462ms","start":"2026-04-28T06:09:28.542873Z","end":"2026-04-28T06:09:28.892208Z","steps":["trace[952462447] 'agreement among raft nodes before linearized reading' (duration: 349.210208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.892962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.542854Z","time spent":"350.099634ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:09:28.893293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.758193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:09:28.8928Z","caller":"traceutil/trace.go:171","msg":"trace[1927510153] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4848; }","duration":"662.363198ms","start":"2026-04-28T06:09:28.230299Z","end":"2026-04-28T06:09:28.892662Z","steps":["trace[1927510153] 'agreement among raft nodes before linearized reading' (duration: 661.771098ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:28.893353Z","caller":"traceutil/trace.go:171","msg":"trace[956152787] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"442.469558ms","start":"2026-04-28T06:09:28.450859Z","end":"2026-04-28T06:09:28.893328Z","steps":["trace[956152787] 'process raft request' (duration: 440.081388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:28.893354Z","caller":"traceutil/trace.go:171","msg":"trace[1734319330] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4848; }","duration":"811.850598ms","start":"2026-04-28T06:09:28.081476Z","end":"2026-04-28T06:09:28.893326Z","steps":["trace[1734319330] 'agreement among raft nodes before linearized reading' (duration: 809.575812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:28.893397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.081453Z","time spent":"811.932034ms","remote":"127.0.0.1:42188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:28.893464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.450828Z","time spent":"442.554743ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:28.893368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:28.230283Z","time spent":"663.073804ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-28T06:09:29.028062Z","caller":"traceutil/trace.go:171","msg":"trace[368252995] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5027; }","duration":"111.667957ms","start":"2026-04-28T06:09:28.916374Z","end":"2026-04-28T06:09:29.028042Z","steps":["trace[368252995] 'read index received' (duration: 111.505045ms)","trace[368252995] 'applied index is now lower than readState.Index' (duration: 162.022µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:29.028135Z","caller":"traceutil/trace.go:171","msg":"trace[1675116971] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"125.449297ms","start":"2026-04-28T06:09:28.902625Z","end":"2026-04-28T06:09:29.028074Z","steps":["trace[1675116971] 'process raft request' (duration: 125.306854ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.028102Z","caller":"traceutil/trace.go:171","msg":"trace[1186022523] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"125.398069ms","start":"2026-04-28T06:09:28.902688Z","end":"2026-04-28T06:09:29.028086Z","steps":["trace[1186022523] 'process raft request' (duration: 125.286395ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.028327Z","caller":"traceutil/trace.go:171","msg":"trace[602421934] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"125.364992ms","start":"2026-04-28T06:09:28.902799Z","end":"2026-04-28T06:09:29.028164Z","steps":["trace[602421934] 'process raft request' (duration: 125.207319ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.02844Z","caller":"traceutil/trace.go:171","msg":"trace[1058956597] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"128.906685ms","start":"2026-04-28T06:09:28.899461Z","end":"2026-04-28T06:09:29.028368Z","steps":["trace[1058956597] 'process raft request' (duration: 128.336793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.028739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.246238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-28T06:09:29.028823Z","caller":"traceutil/trace.go:171","msg":"trace[781184805] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4e3fa27c-bf64-4529-a307-fd1ecdab1395; range_end:; response_count:1; response_revision:4853; }","duration":"125.362472ms","start":"2026-04-28T06:09:28.903437Z","end":"2026-04-28T06:09:29.0288Z","steps":["trace[781184805] 'agreement among raft nodes before linearized reading' (duration: 124.900505ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.11103Z","caller":"traceutil/trace.go:171","msg":"trace[131793617] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"188.098764ms","start":"2026-04-28T06:09:28.922908Z","end":"2026-04-28T06:09:29.111007Z","steps":["trace[131793617] 'process raft request' (duration: 187.920013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.111174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.543502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17204"} {"level":"info","ts":"2026-04-28T06:09:29.11127Z","caller":"traceutil/trace.go:171","msg":"trace[1390950083] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4856; }","duration":"190.664256ms","start":"2026-04-28T06:09:28.920558Z","end":"2026-04-28T06:09:29.111223Z","steps":["trace[1390950083] 'agreement among raft nodes before linearized reading' (duration: 190.484435ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.111265Z","caller":"traceutil/trace.go:171","msg":"trace[2017123141] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"181.494254ms","start":"2026-04-28T06:09:28.929716Z","end":"2026-04-28T06:09:29.11121Z","steps":["trace[2017123141] 'process raft request' (duration: 181.273875ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.111311Z","caller":"traceutil/trace.go:171","msg":"trace[325257718] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"188.342382ms","start":"2026-04-28T06:09:28.92292Z","end":"2026-04-28T06:09:29.111263Z","steps":["trace[325257718] 'process raft request' (duration: 188.022518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.111558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.900723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:09:29.111636Z","caller":"traceutil/trace.go:171","msg":"trace[941343722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4856; }","duration":"148.029967ms","start":"2026-04-28T06:09:28.963588Z","end":"2026-04-28T06:09:29.111618Z","steps":["trace[941343722] 'agreement among raft nodes before linearized reading' (duration: 147.675294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.23212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.504241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:29.232242Z","caller":"traceutil/trace.go:171","msg":"trace[1283803852] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"111.540873ms","start":"2026-04-28T06:09:29.120682Z","end":"2026-04-28T06:09:29.232223Z","steps":["trace[1283803852] 'compare' (duration: 106.343979ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.246265Z","caller":"traceutil/trace.go:171","msg":"trace[1623076261] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"123.519583ms","start":"2026-04-28T06:09:29.122735Z","end":"2026-04-28T06:09:29.246255Z","steps":["trace[1623076261] 'process raft request' (duration: 123.380169ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.24656Z","caller":"traceutil/trace.go:171","msg":"trace[1349425197] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"122.299783ms","start":"2026-04-28T06:09:29.124252Z","end":"2026-04-28T06:09:29.246552Z","steps":["trace[1349425197] 'process raft request' (duration: 121.95918ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.247039Z","caller":"traceutil/trace.go:171","msg":"trace[31860766] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5035; }","duration":"114.541775ms","start":"2026-04-28T06:09:29.132445Z","end":"2026-04-28T06:09:29.246987Z","steps":["trace[31860766] 'read index received' (duration: 113.670908ms)","trace[31860766] 'applied index is now lower than readState.Index' (duration: 867.598µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:29.247322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.865489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-28T06:09:29.247323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.504652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:29.247395Z","caller":"traceutil/trace.go:171","msg":"trace[2045479510] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4859; }","duration":"114.952374ms","start":"2026-04-28T06:09:29.132423Z","end":"2026-04-28T06:09:29.247375Z","steps":["trace[2045479510] 'agreement among raft nodes before linearized reading' (duration: 114.722836ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.247452Z","caller":"traceutil/trace.go:171","msg":"trace[1965995194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4859; }","duration":"107.656214ms","start":"2026-04-28T06:09:29.139778Z","end":"2026-04-28T06:09:29.247435Z","steps":["trace[1965995194] 'agreement among raft nodes before linearized reading' (duration: 107.430665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.603724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.571602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:29.604177Z","caller":"traceutil/trace.go:171","msg":"trace[652540114] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"343.877433ms","start":"2026-04-28T06:09:29.260269Z","end":"2026-04-28T06:09:29.604147Z","steps":["trace[652540114] 'process raft request' (duration: 91.593546ms)","trace[652540114] 'compare' (duration: 250.3879ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:29.604225Z","caller":"traceutil/trace.go:171","msg":"trace[201061080] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"343.816566ms","start":"2026-04-28T06:09:29.260385Z","end":"2026-04-28T06:09:29.604202Z","steps":["trace[201061080] 'process raft request' (duration: 343.462383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.60433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.260262Z","time spent":"343.982758ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:29.604351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.260377Z","time spent":"343.894602ms","remote":"127.0.0.1:42000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:29.604482Z","caller":"traceutil/trace.go:171","msg":"trace[1855305655] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"342.191996ms","start":"2026-04-28T06:09:29.262251Z","end":"2026-04-28T06:09:29.604443Z","steps":["trace[1855305655] 'process raft request' (duration: 341.857772ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.60453Z","caller":"traceutil/trace.go:171","msg":"trace[854344545] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"342.236484ms","start":"2026-04-28T06:09:29.262278Z","end":"2026-04-28T06:09:29.604514Z","steps":["trace[854344545] 'process raft request' (duration: 341.90644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.604592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.262269Z","time spent":"342.292131ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:29.604653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.262244Z","time spent":"342.322599ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1392,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:29.604682Z","caller":"traceutil/trace.go:171","msg":"trace[613580065] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"342.130239ms","start":"2026-04-28T06:09:29.262536Z","end":"2026-04-28T06:09:29.604666Z","steps":["trace[613580065] 'process raft request' (duration: 341.71403ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:29.604796Z","caller":"traceutil/trace.go:171","msg":"trace[850803258] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"336.773563ms","start":"2026-04-28T06:09:29.268008Z","end":"2026-04-28T06:09:29.604782Z","steps":["trace[850803258] 'process raft request' (duration: 336.593702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:29.604809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.26251Z","time spent":"342.219335ms","remote":"127.0.0.1:42000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1272,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:29.604898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.268002Z","time spent":"336.83216ms","remote":"127.0.0.1:42284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15332,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:30.090266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.054949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:30.090373Z","caller":"traceutil/trace.go:171","msg":"trace[157655576] linearizableReadLoop","detail":"{readStateIndex:5045; appliedIndex:5044; }","duration":"327.855071ms","start":"2026-04-28T06:09:29.762502Z","end":"2026-04-28T06:09:30.090357Z","steps":["trace[157655576] 'read index received' (duration: 49.718µs)","trace[157655576] 'applied index is now lower than readState.Index' (duration: 327.804283ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:30.090455Z","caller":"traceutil/trace.go:171","msg":"trace[313068700] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"462.837689ms","start":"2026-04-28T06:09:29.627607Z","end":"2026-04-28T06:09:30.090444Z","steps":["trace[313068700] 'process raft request' (duration: 109.509153ms)","trace[313068700] 'compare' (duration: 352.943105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:30.090514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.627587Z","time spent":"462.898346ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:30.090685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.280215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:30.090819Z","caller":"traceutil/trace.go:171","msg":"trace[136562447] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4867; }","duration":"195.469876ms","start":"2026-04-28T06:09:29.895325Z","end":"2026-04-28T06:09:30.090795Z","steps":["trace[136562447] 'agreement among raft nodes before linearized reading' (duration: 195.276105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:30.090755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.249801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-28T06:09:30.09093Z","caller":"traceutil/trace.go:171","msg":"trace[210321618] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4867; }","duration":"328.456222ms","start":"2026-04-28T06:09:29.762458Z","end":"2026-04-28T06:09:30.090914Z","steps":["trace[210321618] 'agreement among raft nodes before linearized reading' (duration: 328.168266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:30.090971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:29.762437Z","time spent":"328.524459ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-28T06:09:30.474077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.369409ms","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-04-28T06:09:30.474156Z","caller":"traceutil/trace.go:171","msg":"trace[1421684984] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4867; }","duration":"213.488154ms","start":"2026-04-28T06:09:30.260648Z","end":"2026-04-28T06:09:30.474137Z","steps":["trace[1421684984] 'range keys from in-memory index tree' (duration: 213.209227ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:30.71285Z","caller":"traceutil/trace.go:171","msg":"trace[1539914530] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"234.416997ms","start":"2026-04-28T06:09:30.478402Z","end":"2026-04-28T06:09:30.712819Z","steps":["trace[1539914530] 'process raft request' (duration: 234.220087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.145726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.421309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:31.14582Z","caller":"traceutil/trace.go:171","msg":"trace[1215002069] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5047; }","duration":"112.888448ms","start":"2026-04-28T06:09:31.032915Z","end":"2026-04-28T06:09:31.145803Z","steps":["trace[1215002069] 'read index received' (duration: 97.225µs)","trace[1215002069] 'applied index is now lower than readState.Index' (duration: 112.790064ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:31.147042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.019555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:09:31.147101Z","caller":"traceutil/trace.go:171","msg":"trace[1493407062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4870; }","duration":"114.094221ms","start":"2026-04-28T06:09:31.032994Z","end":"2026-04-28T06:09:31.147088Z","steps":["trace[1493407062] 'agreement among raft nodes before linearized reading' (duration: 113.114108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.147223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.730958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:09:31.14735Z","caller":"traceutil/trace.go:171","msg":"trace[1661963897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4870; }","duration":"113.874251ms","start":"2026-04-28T06:09:31.033447Z","end":"2026-04-28T06:09:31.147321Z","steps":["trace[1661963897] 'agreement among raft nodes before linearized reading' (duration: 113.654271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.147241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.657891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:09:31.147528Z","caller":"traceutil/trace.go:171","msg":"trace[917144409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4870; }","duration":"113.981186ms","start":"2026-04-28T06:09:31.033536Z","end":"2026-04-28T06:09:31.147517Z","steps":["trace[917144409] 'agreement among raft nodes before linearized reading' (duration: 113.504119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.147547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.576998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:09:31.147785Z","caller":"traceutil/trace.go:171","msg":"trace[468854313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4870; }","duration":"114.884233ms","start":"2026-04-28T06:09:31.032874Z","end":"2026-04-28T06:09:31.147758Z","steps":["trace[468854313] 'agreement among raft nodes before linearized reading' (duration: 113.118407ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:31.147017Z","caller":"traceutil/trace.go:171","msg":"trace[2005326311] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"267.56596ms","start":"2026-04-28T06:09:30.878607Z","end":"2026-04-28T06:09:31.146173Z","steps":["trace[2005326311] 'process raft request' (duration: 97.620305ms)","trace[2005326311] 'compare' (duration: 169.303046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:31.417027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.943811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:31.417294Z","caller":"traceutil/trace.go:171","msg":"trace[1735692070] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5048; }","duration":"217.013368ms","start":"2026-04-28T06:09:31.200263Z","end":"2026-04-28T06:09:31.417276Z","steps":["trace[1735692070] 'read index received' (duration: 72.747423ms)","trace[1735692070] 'applied index is now lower than readState.Index' (duration: 144.264955ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:31.41732Z","caller":"traceutil/trace.go:171","msg":"trace[194646795] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"265.5723ms","start":"2026-04-28T06:09:31.151715Z","end":"2026-04-28T06:09:31.417287Z","steps":["trace[194646795] 'process raft request' (duration: 121.270167ms)","trace[194646795] 'compare' (duration: 143.709882ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:31.417375Z","caller":"traceutil/trace.go:171","msg":"trace[1442428360] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"265.036426ms","start":"2026-04-28T06:09:31.152328Z","end":"2026-04-28T06:09:31.417364Z","steps":["trace[1442428360] 'process raft request' (duration: 264.897922ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:31.417407Z","caller":"traceutil/trace.go:171","msg":"trace[1731960460] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"265.481805ms","start":"2026-04-28T06:09:31.151897Z","end":"2026-04-28T06:09:31.417379Z","steps":["trace[1731960460] 'process raft request' (duration: 265.296224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.417515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.253206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:31.417545Z","caller":"traceutil/trace.go:171","msg":"trace[659030561] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4874; }","duration":"217.322043ms","start":"2026-04-28T06:09:31.200213Z","end":"2026-04-28T06:09:31.417535Z","steps":["trace[659030561] 'agreement among raft nodes before linearized reading' (duration: 217.265586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:31.417611Z","caller":"traceutil/trace.go:171","msg":"trace[1786262853] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"265.754981ms","start":"2026-04-28T06:09:31.151832Z","end":"2026-04-28T06:09:31.417587Z","steps":["trace[1786262853] 'process raft request' (duration: 265.300013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.699694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.488342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:31.700037Z","caller":"traceutil/trace.go:171","msg":"trace[628505807] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"344.127474ms","start":"2026-04-28T06:09:31.355895Z","end":"2026-04-28T06:09:31.700023Z","steps":["trace[628505807] 'process raft request' (duration: 166.172703ms)","trace[628505807] 'compare' (duration: 177.367219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:31.700117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.355872Z","time spent":"344.20849ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:31.700183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.229877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T06:09:31.700257Z","caller":"traceutil/trace.go:171","msg":"trace[446923015] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4875; }","duration":"353.341092ms","start":"2026-04-28T06:09:31.346899Z","end":"2026-04-28T06:09:31.70024Z","steps":["trace[446923015] 'agreement among raft nodes before linearized reading' (duration: 353.096944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.700311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.346885Z","time spent":"353.418088ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-28T06:09:31.700432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.194508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk.18aa705e9862d457\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-28T06:09:31.700482Z","caller":"traceutil/trace.go:171","msg":"trace[1514734934] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk.18aa705e9862d457; range_end:; response_count:1; response_revision:4875; }","duration":"391.290192ms","start":"2026-04-28T06:09:31.309176Z","end":"2026-04-28T06:09:31.700466Z","steps":["trace[1514734934] 'agreement among raft nodes before linearized reading' (duration: 391.117841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.700523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.579556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-28T06:09:31.700525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.309164Z","time spent":"391.35185ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":930,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-fvjqk.18aa705e9862d457\" "} {"level":"info","ts":"2026-04-28T06:09:31.700565Z","caller":"traceutil/trace.go:171","msg":"trace[990961686] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4875; }","duration":"409.646593ms","start":"2026-04-28T06:09:31.290903Z","end":"2026-04-28T06:09:31.70055Z","steps":["trace[990961686] 'agreement among raft nodes before linearized reading' (duration: 409.547448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.700599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.290883Z","time spent":"409.70853ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T06:09:31.699989Z","caller":"traceutil/trace.go:171","msg":"trace[1532308983] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5052; }","duration":"282.638741ms","start":"2026-04-28T06:09:31.417331Z","end":"2026-04-28T06:09:31.69997Z","steps":["trace[1532308983] 'read index received' (duration: 104.687411ms)","trace[1532308983] 'applied index is now lower than readState.Index' (duration: 177.95008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:31.701092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.181981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:09:31.70143Z","caller":"traceutil/trace.go:171","msg":"trace[1100831876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4875; }","duration":"465.555853ms","start":"2026-04-28T06:09:31.235858Z","end":"2026-04-28T06:09:31.701414Z","steps":["trace[1100831876] 'agreement among raft nodes before linearized reading' (duration: 465.008959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:31.701539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.235847Z","time spent":"465.678537ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:09:32.078939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.05113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:32.079052Z","caller":"traceutil/trace.go:171","msg":"trace[873445271] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"467.91649ms","start":"2026-04-28T06:09:31.611114Z","end":"2026-04-28T06:09:32.07903Z","steps":["trace[873445271] 'process raft request' (duration: 228.681679ms)","trace[873445271] 'compare' (duration: 238.912756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:32.079122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.611089Z","time spent":"468.002476ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:32.222781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.108933ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T06:09:32.223203Z","caller":"traceutil/trace.go:171","msg":"trace[2038413902] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"517.912048ms","start":"2026-04-28T06:09:31.705269Z","end":"2026-04-28T06:09:32.223181Z","steps":["trace[2038413902] 'process raft request' (duration: 517.767385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.223322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.705256Z","time spent":"517.994445ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:09:32.223387Z","caller":"traceutil/trace.go:171","msg":"trace[755285495] linearizableReadLoop","detail":"{readStateIndex:5055; appliedIndex:5053; }","duration":"522.517478ms","start":"2026-04-28T06:09:31.700827Z","end":"2026-04-28T06:09:32.223344Z","steps":["trace[755285495] 'read index received' (duration: 138.988978ms)","trace[755285495] 'applied index is now lower than readState.Index' (duration: 383.52705ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:32.22329Z","caller":"traceutil/trace.go:171","msg":"trace[473912712] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"518.146517ms","start":"2026-04-28T06:09:31.705101Z","end":"2026-04-28T06:09:32.223248Z","steps":["trace[473912712] 'process raft request' (duration: 517.884849ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:32.223204Z","caller":"traceutil/trace.go:171","msg":"trace[1914631793] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"520.494285ms","start":"2026-04-28T06:09:31.702659Z","end":"2026-04-28T06:09:32.223153Z","steps":["trace[1914631793] 'process raft request' (duration: 520.210568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:32.223492Z","caller":"traceutil/trace.go:171","msg":"trace[342284191] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"518.091761ms","start":"2026-04-28T06:09:31.705358Z","end":"2026-04-28T06:09:32.22345Z","steps":["trace[342284191] 'process raft request' (duration: 517.788585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.223644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.705352Z","time spent":"518.212964ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:32.223647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.702638Z","time spent":"520.886716ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:32.223708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.172138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:32.223758Z","caller":"traceutil/trace.go:171","msg":"trace[679753119] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:4880; }","duration":"238.263174ms","start":"2026-04-28T06:09:31.985478Z","end":"2026-04-28T06:09:32.223741Z","steps":["trace[679753119] 'agreement among raft nodes before linearized reading' (duration: 238.188987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.22373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.287978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:32.223863Z","caller":"traceutil/trace.go:171","msg":"trace[1788623033] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4880; }","duration":"522.454491ms","start":"2026-04-28T06:09:31.701377Z","end":"2026-04-28T06:09:32.223832Z","steps":["trace[1788623033] 'agreement among raft nodes before linearized reading' (duration: 522.167964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.223926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.701355Z","time spent":"522.556666ms","remote":"127.0.0.1:49350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:32.223936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.958959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-28T06:09:32.224004Z","caller":"traceutil/trace.go:171","msg":"trace[104308640] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4880; }","duration":"713.094873ms","start":"2026-04-28T06:09:31.510891Z","end":"2026-04-28T06:09:32.223986Z","steps":["trace[104308640] 'agreement among raft nodes before linearized reading' (duration: 712.574868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.224018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.847435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-28T06:09:32.224063Z","caller":"traceutil/trace.go:171","msg":"trace[1355548789] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4880; }","duration":"504.95553ms","start":"2026-04-28T06:09:31.719095Z","end":"2026-04-28T06:09:32.224051Z","steps":["trace[1355548789] 'agreement among raft nodes before linearized reading' (duration: 504.786978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.224104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.719063Z","time spent":"505.034956ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-28T06:09:32.224062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.510873Z","time spent":"713.178249ms","remote":"127.0.0.1:32948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-28T06:09:32.223959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.402626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:32.224352Z","caller":"traceutil/trace.go:171","msg":"trace[1372192578] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4880; }","duration":"101.757849ms","start":"2026-04-28T06:09:32.122546Z","end":"2026-04-28T06:09:32.224303Z","steps":["trace[1372192578] 'agreement among raft nodes before linearized reading' (duration: 101.382917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:32.225018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:31.705087Z","time spent":"518.432134ms","remote":"127.0.0.1:42088","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-04-28T06:10:07.835612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.366905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:10:07.835713Z","caller":"traceutil/trace.go:171","msg":"trace[400114305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5189; }","duration":"185.514171ms","start":"2026-04-28T06:10:07.650178Z","end":"2026-04-28T06:10:07.835693Z","steps":["trace[400114305] 'range keys from in-memory index tree' (duration: 185.172462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:07.835889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.077734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:10:07.835972Z","caller":"traceutil/trace.go:171","msg":"trace[220617731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5189; }","duration":"184.19023ms","start":"2026-04-28T06:10:07.651764Z","end":"2026-04-28T06:10:07.835954Z","steps":["trace[220617731] 'range keys from in-memory index tree' (duration: 183.950198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:07.836212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.20052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:10:07.836281Z","caller":"traceutil/trace.go:171","msg":"trace[1276130099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5189; }","duration":"183.304807ms","start":"2026-04-28T06:10:07.652962Z","end":"2026-04-28T06:10:07.836267Z","steps":["trace[1276130099] 'range keys from in-memory index tree' (duration: 182.997036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:17.41723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.808332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T06:10:17.417397Z","caller":"traceutil/trace.go:171","msg":"trace[940616626] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5244; }","duration":"168.004176ms","start":"2026-04-28T06:10:17.249368Z","end":"2026-04-28T06:10:17.417372Z","steps":["trace[940616626] 'range keys from in-memory index tree' (duration: 167.620267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:17.417521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.645849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:10:17.417683Z","caller":"traceutil/trace.go:171","msg":"trace[1041488132] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5244; }","duration":"152.848374ms","start":"2026-04-28T06:10:17.26482Z","end":"2026-04-28T06:10:17.417668Z","steps":["trace[1041488132] 'count revisions from in-memory index tree' (duration: 152.58255ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:17.418042Z","caller":"traceutil/trace.go:171","msg":"trace[1523536183] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5434; }","duration":"115.60385ms","start":"2026-04-28T06:10:17.302427Z","end":"2026-04-28T06:10:17.418031Z","steps":["trace[1523536183] 'read index received' (duration: 32.898698ms)","trace[1523536183] 'applied index is now lower than readState.Index' (duration: 82.704332ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:17.418145Z","caller":"traceutil/trace.go:171","msg":"trace[647804993] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"126.491224ms","start":"2026-04-28T06:10:17.291611Z","end":"2026-04-28T06:10:17.418102Z","steps":["trace[647804993] 'process raft request' (duration: 43.745713ms)","trace[647804993] 'compare' (duration: 81.842485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:17.419287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.854567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194894"} {"level":"info","ts":"2026-04-28T06:10:17.419339Z","caller":"traceutil/trace.go:171","msg":"trace[134719231] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5245; }","duration":"116.931515ms","start":"2026-04-28T06:10:17.302394Z","end":"2026-04-28T06:10:17.419326Z","steps":["trace[134719231] 'agreement among raft nodes before linearized reading' (duration: 115.756696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:17.758656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.415025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:10:17.758739Z","caller":"traceutil/trace.go:171","msg":"trace[1766102663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5247; }","duration":"107.555911ms","start":"2026-04-28T06:10:17.651162Z","end":"2026-04-28T06:10:17.758718Z","steps":["trace[1766102663] 'range keys from in-memory index tree' (duration: 107.183201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:49.906582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.693914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:10:49.906684Z","caller":"traceutil/trace.go:171","msg":"trace[866428801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5493; }","duration":"272.835931ms","start":"2026-04-28T06:10:49.633827Z","end":"2026-04-28T06:10:49.906663Z","steps":["trace[866428801] 'range keys from in-memory index tree' (duration: 272.632001ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:50.309185Z","caller":"traceutil/trace.go:171","msg":"trace[654972859] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"120.974383ms","start":"2026-04-28T06:10:50.188185Z","end":"2026-04-28T06:10:50.309159Z","steps":["trace[654972859] 'process raft request' (duration: 32.656806ms)","trace[654972859] 'compare' (duration: 88.210492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:56.552295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626164915731200,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:10:56.643883Z","caller":"traceutil/trace.go:171","msg":"trace[1201553271] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"593.491184ms","start":"2026-04-28T06:10:56.050364Z","end":"2026-04-28T06:10:56.643855Z","steps":["trace[1201553271] 'process raft request' (duration: 574.100762ms)","trace[1201553271] 'compare' (duration: 18.89248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:56.644029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.05035Z","time spent":"593.607208ms","remote":"127.0.0.1:42088","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-04-28T06:10:56.662933Z","caller":"traceutil/trace.go:171","msg":"trace[899637051] linearizableReadLoop","detail":"{readStateIndex:5723; appliedIndex:5721; }","duration":"610.968273ms","start":"2026-04-28T06:10:56.051942Z","end":"2026-04-28T06:10:56.66291Z","steps":["trace[899637051] 'read index received' (duration: 572.535595ms)","trace[899637051] 'applied index is now lower than readState.Index' (duration: 38.431568ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:56.663112Z","caller":"traceutil/trace.go:171","msg":"trace[2110526891] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"156.671256ms","start":"2026-04-28T06:10:56.506422Z","end":"2026-04-28T06:10:56.663094Z","steps":["trace[2110526891] 'process raft request' (duration: 156.392614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:56.663326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.339018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:10:56.663409Z","caller":"traceutil/trace.go:171","msg":"trace[1292536488] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5525; }","duration":"611.467404ms","start":"2026-04-28T06:10:56.051921Z","end":"2026-04-28T06:10:56.663389Z","steps":["trace[1292536488] 'agreement among raft nodes before linearized reading' (duration: 611.275126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:56.663482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.051912Z","time spent":"611.558738ms","remote":"127.0.0.1:42094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-28T06:10:56.663673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.703304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8316"} {"level":"info","ts":"2026-04-28T06:10:56.66372Z","caller":"traceutil/trace.go:171","msg":"trace[1048338771] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5525; }","duration":"611.788428ms","start":"2026-04-28T06:10:56.05192Z","end":"2026-04-28T06:10:56.663709Z","steps":["trace[1048338771] 'agreement among raft nodes before linearized reading' (duration: 611.628461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:56.663785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.051905Z","time spent":"611.84488ms","remote":"127.0.0.1:42070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8340,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-28T06:10:56.663993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.705299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T06:10:56.664003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.286301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T06:10:56.664104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.678786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:10:56.664135Z","caller":"traceutil/trace.go:171","msg":"trace[2105327909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5525; }","duration":"560.725649ms","start":"2026-04-28T06:10:56.103401Z","end":"2026-04-28T06:10:56.664127Z","steps":["trace[2105327909] 'agreement among raft nodes before linearized reading' (duration: 560.650695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:56.664165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.103373Z","time spent":"560.786451ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:10:56.664185Z","caller":"traceutil/trace.go:171","msg":"trace[425524975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5525; }","duration":"522.439077ms","start":"2026-04-28T06:10:56.141662Z","end":"2026-04-28T06:10:56.664101Z","steps":["trace[425524975] 'agreement among raft nodes before linearized reading' (duration: 522.002248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:56.664369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.141651Z","time spent":"522.704138ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:10:56.664944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.415293ms","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-04-28T06:10:56.665204Z","caller":"traceutil/trace.go:171","msg":"trace[1487921094] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5525; }","duration":"155.485826ms","start":"2026-04-28T06:10:56.509491Z","end":"2026-04-28T06:10:56.664977Z","steps":["trace[1487921094] 'agreement among raft nodes before linearized reading' (duration: 155.390352ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:10:56.66403Z","caller":"traceutil/trace.go:171","msg":"trace[265497063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5525; }","duration":"529.774152ms","start":"2026-04-28T06:10:56.134244Z","end":"2026-04-28T06:10:56.664018Z","steps":["trace[265497063] 'agreement among raft nodes before linearized reading' (duration: 529.686328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:56.665619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.134227Z","time spent":"531.37581ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:10:57.22612Z","caller":"traceutil/trace.go:171","msg":"trace[1341506145] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5724; }","duration":"502.592884ms","start":"2026-04-28T06:10:56.723507Z","end":"2026-04-28T06:10:57.2261Z","steps":["trace[1341506145] 'read index received' (duration: 467.842116ms)","trace[1341506145] 'applied index is now lower than readState.Index' (duration: 34.749888ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:10:57.226602Z","caller":"traceutil/trace.go:171","msg":"trace[1342260843] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"556.830909ms","start":"2026-04-28T06:10:56.669744Z","end":"2026-04-28T06:10:57.226575Z","steps":["trace[1342260843] 'process raft request' (duration: 556.311237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:57.226778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.669736Z","time spent":"556.921162ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:10:57.22681Z","caller":"traceutil/trace.go:171","msg":"trace[1828214394] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"557.034318ms","start":"2026-04-28T06:10:56.669672Z","end":"2026-04-28T06:10:57.226706Z","steps":["trace[1828214394] 'process raft request' (duration: 521.729546ms)","trace[1828214394] 'compare' (duration: 34.520628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:10:57.226922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.375327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:10:57.226996Z","caller":"traceutil/trace.go:171","msg":"trace[1576672635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5528; }","duration":"503.531473ms","start":"2026-04-28T06:10:56.723449Z","end":"2026-04-28T06:10:57.22698Z","steps":["trace[1576672635] 'agreement among raft nodes before linearized reading' (duration: 503.281093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:57.22704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.723436Z","time spent":"503.595886ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:10:57.227263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.669652Z","time spent":"557.241006ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:10:57.230377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.066587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T06:10:57.230436Z","caller":"traceutil/trace.go:171","msg":"trace[2128595607] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5529; }","duration":"400.162891ms","start":"2026-04-28T06:10:56.830258Z","end":"2026-04-28T06:10:57.230421Z","steps":["trace[2128595607] 'agreement among raft nodes before linearized reading' (duration: 399.795585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:10:57.23047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:10:56.83024Z","time spent":"400.222163ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T06:11:02.760617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2878} {"level":"info","ts":"2026-04-28T06:11:02.837466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2878,"took":"75.700077ms","hash":1291270116,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":29147136,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T06:11:02.83753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1291270116,"revision":2878,"compact-revision":-1} {"level":"info","ts":"2026-04-28T06:13:08.188127Z","caller":"traceutil/trace.go:171","msg":"trace[734154836] transaction","detail":"{read_only:false; response_revision:6441; number_of_response:1; }","duration":"105.966489ms","start":"2026-04-28T06:13:08.082084Z","end":"2026-04-28T06:13:08.18805Z","steps":["trace[734154836] 'process raft request' (duration: 105.786236ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:08.274727Z","caller":"traceutil/trace.go:171","msg":"trace[745801027] transaction","detail":"{read_only:false; response_revision:6442; number_of_response:1; }","duration":"192.434567ms","start":"2026-04-28T06:13:08.08227Z","end":"2026-04-28T06:13:08.274705Z","steps":["trace[745801027] 'process raft request' (duration: 192.260734ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:08.274948Z","caller":"traceutil/trace.go:171","msg":"trace[825591156] transaction","detail":"{read_only:false; response_revision:6443; number_of_response:1; }","duration":"192.603539ms","start":"2026-04-28T06:13:08.082326Z","end":"2026-04-28T06:13:08.27493Z","steps":["trace[825591156] 'process raft request' (duration: 192.342165ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:08.275284Z","caller":"traceutil/trace.go:171","msg":"trace[1547252993] transaction","detail":"{read_only:false; response_revision:6444; number_of_response:1; }","duration":"116.449011ms","start":"2026-04-28T06:13:08.158824Z","end":"2026-04-28T06:13:08.275273Z","steps":["trace[1547252993] 'process raft request' (duration: 116.047475ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:13:31.106398Z","caller":"traceutil/trace.go:171","msg":"trace[736575039] transaction","detail":"{read_only:false; response_revision:6614; number_of_response:1; }","duration":"153.860831ms","start":"2026-04-28T06:13:30.952514Z","end":"2026-04-28T06:13:31.106375Z","steps":["trace[736575039] 'process raft request' (duration: 153.727619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:36.17246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.285312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"warn","ts":"2026-04-28T06:15:36.172549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.592875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cert-manager-controller-certificatesigningrequests\" ","response":"range_response_count:1 size:1216"} {"level":"warn","ts":"2026-04-28T06:15:36.172373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.019592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T06:15:36.172698Z","caller":"traceutil/trace.go:171","msg":"trace[1516952482] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:7272; }","duration":"167.410484ms","start":"2026-04-28T06:15:36.005268Z","end":"2026-04-28T06:15:36.172678Z","steps":["trace[1516952482] 'range keys from in-memory index tree' (duration: 166.834122ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:36.172589Z","caller":"traceutil/trace.go:171","msg":"trace[2106589408] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:7272; }","duration":"149.498753ms","start":"2026-04-28T06:15:36.023065Z","end":"2026-04-28T06:15:36.172564Z","steps":["trace[2106589408] 'range keys from in-memory index tree' (duration: 149.203401ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:36.172619Z","caller":"traceutil/trace.go:171","msg":"trace[191341706] range","detail":"{range_begin:/registry/clusterrolebindings/cert-manager-controller-certificatesigningrequests; range_end:; response_count:1; response_revision:7272; }","duration":"208.681527ms","start":"2026-04-28T06:15:35.963916Z","end":"2026-04-28T06:15:36.172597Z","steps":["trace[191341706] 'range keys from in-memory index tree' (duration: 208.438735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:41.424462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.476249ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T06:15:41.424545Z","caller":"traceutil/trace.go:171","msg":"trace[747958776] linearizableReadLoop","detail":"{readStateIndex:7565; appliedIndex:7564; }","duration":"202.048684ms","start":"2026-04-28T06:15:41.222482Z","end":"2026-04-28T06:15:41.42453Z","steps":["trace[747958776] 'read index received' (duration: 25.489445ms)","trace[747958776] 'applied index is now lower than readState.Index' (duration: 176.557859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:15:41.42466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.166285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-04-28T06:15:41.424679Z","caller":"traceutil/trace.go:171","msg":"trace[329691283] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:7299; }","duration":"202.223196ms","start":"2026-04-28T06:15:41.222448Z","end":"2026-04-28T06:15:41.424672Z","steps":["trace[329691283] 'agreement among raft nodes before linearized reading' (duration: 202.160036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:41.424771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.007244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:15:41.424827Z","caller":"traceutil/trace.go:171","msg":"trace[1176529982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7299; }","duration":"202.093855ms","start":"2026-04-28T06:15:41.222721Z","end":"2026-04-28T06:15:41.424815Z","steps":["trace[1176529982] 'agreement among raft nodes before linearized reading' (duration: 201.923634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:41.42495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.540319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-28T06:15:41.425048Z","caller":"traceutil/trace.go:171","msg":"trace[276939727] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7299; }","duration":"105.65519ms","start":"2026-04-28T06:15:41.319373Z","end":"2026-04-28T06:15:41.425028Z","steps":["trace[276939727] 'agreement among raft nodes before linearized reading' (duration: 105.283788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:41.425115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.816315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-04-28T06:15:41.42518Z","caller":"traceutil/trace.go:171","msg":"trace[870922778] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:1; response_revision:7299; }","duration":"179.898675ms","start":"2026-04-28T06:15:41.245262Z","end":"2026-04-28T06:15:41.425161Z","steps":["trace[870922778] 'agreement among raft nodes before linearized reading' (duration: 179.424213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:51.781142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.1556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/cert-manager/\" range_end:\"/registry/events/cert-manager0\" limit:500 ","response":"range_response_count:52 size:42239"} {"level":"info","ts":"2026-04-28T06:15:51.78123Z","caller":"traceutil/trace.go:171","msg":"trace[602171255] range","detail":"{range_begin:/registry/events/cert-manager/; range_end:/registry/events/cert-manager0; response_count:52; response_revision:7351; }","duration":"157.262751ms","start":"2026-04-28T06:15:51.623949Z","end":"2026-04-28T06:15:51.781212Z","steps":["trace[602171255] 'range keys from in-memory index tree' (duration: 156.798358ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:52.060389Z","caller":"traceutil/trace.go:171","msg":"trace[1545523867] transaction","detail":"{read_only:false; response_revision:7352; number_of_response:1; }","duration":"108.318744ms","start":"2026-04-28T06:15:51.952046Z","end":"2026-04-28T06:15:52.060365Z","steps":["trace[1545523867] 'process raft request' (duration: 108.061743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:15:57.162032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.298205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-28T06:15:57.162113Z","caller":"traceutil/trace.go:171","msg":"trace[2055088076] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:7379; }","duration":"117.421815ms","start":"2026-04-28T06:15:57.044672Z","end":"2026-04-28T06:15:57.162094Z","steps":["trace[2055088076] 'range keys from in-memory index tree' (duration: 116.934954ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:15:59.418053Z","caller":"traceutil/trace.go:171","msg":"trace[116374222] transaction","detail":"{read_only:false; response_revision:7390; number_of_response:1; }","duration":"157.46694ms","start":"2026-04-28T06:15:59.260561Z","end":"2026-04-28T06:15:59.418028Z","steps":["trace[116374222] 'process raft request' (duration: 156.630377ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:16:02.76812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5584} {"level":"info","ts":"2026-04-28T06:16:02.887312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5584,"took":"118.025799ms","hash":1313991388,"current-db-size-bytes":53829632,"current-db-size":"54 MB","current-db-size-in-use-bytes":18292736,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T06:16:02.887368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1313991388,"revision":5584,"compact-revision":2878}