{"level":"warn","ts":"2026-10-09T09:14:21.170841Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T09:14:21.17099Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-10-09T09:14:21.171094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T09:14:21.171107Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-10-09T09:14:21.171142Z","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-10-09T09:14:21.172239Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-10-09T09:14:21.172393Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-10-09T09:14:21.178065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.297361ms"} {"level":"info","ts":"2026-10-09T09:14:21.194789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-10-09T09:14:21.194983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T09:14:21.195068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-10-09T09:14:21.195098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T09:14:21.195156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-10-09T09:14:21.195461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-10-09T09:14:21.202692Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T09:14:21.206447Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T09:14:21.208201Z","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-10-09T09:14:21.210834Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T09:14:21.211462Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T09:14:21.211615Z","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-10-09T09:14:21.211695Z","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-10-09T09:14:21.211715Z","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-10-09T09:14:21.212815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-10-09T09:14:21.213016Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-10-09T09:14:21.214148Z","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-10-09T09:14:21.214273Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-10-09T09:14:21.214344Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-10-09T09:14:21.21448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T09:14:21.214597Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T09:14:21.896664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T09:14:21.896731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T09:14:21.896755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-10-09T09:14:21.896775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-10-09T09:14:21.896786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-10-09T09:14:21.896801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-10-09T09:14:21.896814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-10-09T09:14:21.898263Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:21.899533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T09:14:21.89951Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T09:14:21.899975Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T09:14:21.899844Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T09:14:21.900049Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T09:14:21.900465Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:21.900665Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:21.900712Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:21.903121Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T09:14:21.903784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-10-09T09:14:36.976273Z","caller":"traceutil/trace.go:171","msg":"trace[1292115540] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"140.285934ms","start":"2026-10-09T09:14:36.835962Z","end":"2026-10-09T09:14:36.976248Z","steps":["trace[1292115540] 'process raft request' (duration: 133.269964ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:14:37.142699Z","caller":"traceutil/trace.go:171","msg":"trace[1760238878] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"150.127808ms","start":"2026-10-09T09:14:36.992544Z","end":"2026-10-09T09:14:37.142672Z","steps":["trace[1760238878] 'process raft request' (duration: 121.416564ms)","trace[1760238878] 'compare' (duration: 28.585661ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:14:37.256093Z","caller":"traceutil/trace.go:171","msg":"trace[1692877267] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"125.491808ms","start":"2026-10-09T09:14:37.130558Z","end":"2026-10-09T09:14:37.25605Z","steps":["trace[1692877267] 'process raft request' (duration: 125.108679ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:15:10.176182Z","caller":"traceutil/trace.go:171","msg":"trace[656634359] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"147.859121ms","start":"2026-10-09T09:15:10.028295Z","end":"2026-10-09T09:15:10.176154Z","steps":["trace[656634359] 'process raft request' (duration: 147.607575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:10.528433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.754211ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-09T09:15:10.538293Z","caller":"traceutil/trace.go:171","msg":"trace[283163577] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"125.903208ms","start":"2026-10-09T09:15:10.412352Z","end":"2026-10-09T09:15:10.538255Z","steps":["trace[283163577] 'process raft request' (duration: 125.16109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:10.990899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.972469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:15:10.99109Z","caller":"traceutil/trace.go:171","msg":"trace[119238823] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"378.001729ms","start":"2026-10-09T09:15:10.613047Z","end":"2026-10-09T09:15:10.991049Z","steps":["trace[119238823] 'process raft request' (duration: 19.376426ms)","trace[119238823] 'compare' (duration: 357.692413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:15:10.991261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:15:10.613013Z","time spent":"378.165442ms","remote":"127.0.0.1:59540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T09:15:10.991034Z","caller":"traceutil/trace.go:171","msg":"trace[1074704786] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"330.250586ms","start":"2026-10-09T09:15:10.660763Z","end":"2026-10-09T09:15:10.991014Z","steps":["trace[1074704786] 'read index received' (duration: 44.531µs)","trace[1074704786] 'applied index is now lower than readState.Index' (duration: 330.204395ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:15:10.991773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.998753ms","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-10-09T09:15:10.991819Z","caller":"traceutil/trace.go:171","msg":"trace[575692021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"331.107045ms","start":"2026-10-09T09:15:10.6607Z","end":"2026-10-09T09:15:10.991807Z","steps":["trace[575692021] 'agreement among raft nodes before linearized reading' (duration: 330.828299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:10.991991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:15:10.660673Z","time spent":"331.30247ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T09:15:27.206404Z","caller":"traceutil/trace.go:171","msg":"trace[914698107] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"122.816758ms","start":"2026-10-09T09:15:27.083564Z","end":"2026-10-09T09:15:27.206381Z","steps":["trace[914698107] 'process raft request' (duration: 122.681884ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:15:27.400313Z","caller":"traceutil/trace.go:171","msg":"trace[464763643] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"287.351083ms","start":"2026-10-09T09:15:27.112936Z","end":"2026-10-09T09:15:27.400287Z","steps":["trace[464763643] 'read index received' (duration: 93.312765ms)","trace[464763643] 'applied index is now lower than readState.Index' (duration: 194.037328ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:15:27.400467Z","caller":"traceutil/trace.go:171","msg":"trace[1015547406] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"316.681632ms","start":"2026-10-09T09:15:27.083709Z","end":"2026-10-09T09:15:27.40039Z","steps":["trace[1015547406] 'process raft request' (duration: 254.393183ms)","trace[1015547406] 'compare' (duration: 62.077424ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:15:27.4006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.440947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-09T09:15:27.400643Z","caller":"traceutil/trace.go:171","msg":"trace[378299167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"190.546079ms","start":"2026-10-09T09:15:27.210087Z","end":"2026-10-09T09:15:27.400633Z","steps":["trace[378299167] 'agreement among raft nodes before linearized reading' (duration: 190.358115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:27.400693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.713521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f4gl6\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-09T09:15:27.400832Z","caller":"traceutil/trace.go:171","msg":"trace[2143895218] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-f4gl6; range_end:; response_count:1; response_revision:810; }","duration":"287.902086ms","start":"2026-10-09T09:15:27.112902Z","end":"2026-10-09T09:15:27.400804Z","steps":["trace[2143895218] 'agreement among raft nodes before linearized reading' (duration: 287.537528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:27.40106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:15:27.083677Z","time spent":"316.948538ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T09:16:43.409652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.605353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6f5d629f-7271-4143-a39f-f314b2ddf79d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-09T09:16:43.409781Z","caller":"traceutil/trace.go:171","msg":"trace[867443278] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6f5d629f-7271-4143-a39f-f314b2ddf79d; range_end:; response_count:1; response_revision:1881; }","duration":"304.795507ms","start":"2026-10-09T09:16:43.10496Z","end":"2026-10-09T09:16:43.409755Z","steps":["trace[867443278] 'range keys from in-memory index tree' (duration: 304.329867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:16:43.409839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:16:43.104935Z","time spent":"304.886429ms","remote":"127.0.0.1:59356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6f5d629f-7271-4143-a39f-f314b2ddf79d\" "} {"level":"warn","ts":"2026-10-09T09:16:43.791996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.2556ms","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-10-09T09:16:43.792087Z","caller":"traceutil/trace.go:171","msg":"trace[966553387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"144.400174ms","start":"2026-10-09T09:16:43.647666Z","end":"2026-10-09T09:16:43.792066Z","steps":["trace[966553387] 'range keys from in-memory index tree' (duration: 144.022445ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:33.058054Z","caller":"traceutil/trace.go:171","msg":"trace[1683507045] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"119.299761ms","start":"2026-10-09T09:17:32.938711Z","end":"2026-10-09T09:17:33.058011Z","steps":["trace[1683507045] 'process raft request' (duration: 119.058215ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:38.847436Z","caller":"traceutil/trace.go:171","msg":"trace[1356867866] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2319; }","duration":"122.037562ms","start":"2026-10-09T09:17:38.72537Z","end":"2026-10-09T09:17:38.847407Z","steps":["trace[1356867866] 'read index received' (duration: 121.699625ms)","trace[1356867866] 'applied index is now lower than readState.Index' (duration: 336.707µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:17:38.847508Z","caller":"traceutil/trace.go:171","msg":"trace[544658735] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"168.517325ms","start":"2026-10-09T09:17:38.678963Z","end":"2026-10-09T09:17:38.84748Z","steps":["trace[544658735] 'process raft request' (duration: 168.183028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:17:38.847745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.360499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-09T09:17:38.84779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.95076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:17:38.847811Z","caller":"traceutil/trace.go:171","msg":"trace[1299044312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2232; }","duration":"122.479672ms","start":"2026-10-09T09:17:38.725314Z","end":"2026-10-09T09:17:38.847794Z","steps":["trace[1299044312] 'agreement among raft nodes before linearized reading' (duration: 122.295748ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:38.847943Z","caller":"traceutil/trace.go:171","msg":"trace[1942931923] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2232; }","duration":"114.142963ms","start":"2026-10-09T09:17:38.733785Z","end":"2026-10-09T09:17:38.847928Z","steps":["trace[1942931923] 'agreement among raft nodes before linearized reading' (duration: 113.956739ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:18:13.221965Z","caller":"traceutil/trace.go:171","msg":"trace[691509725] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"175.850372ms","start":"2026-10-09T09:18:13.046094Z","end":"2026-10-09T09:18:13.221944Z","steps":["trace[691509725] 'process raft request' (duration: 175.727329ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:18:13.232569Z","caller":"traceutil/trace.go:171","msg":"trace[45117117] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"164.919784ms","start":"2026-10-09T09:18:13.067623Z","end":"2026-10-09T09:18:13.232543Z","steps":["trace[45117117] 'process raft request' (duration: 164.792ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:11.546214Z","caller":"traceutil/trace.go:171","msg":"trace[450911717] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"118.092344ms","start":"2026-10-09T09:23:11.428107Z","end":"2026-10-09T09:23:11.546199Z","steps":["trace[450911717] 'process raft request' (duration: 117.982692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:19.163885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.004571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a8f01a56-a4e6-4754-aedb-b9bbdad5ae5b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-09T09:23:19.163983Z","caller":"traceutil/trace.go:171","msg":"trace[2123053699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a8f01a56-a4e6-4754-aedb-b9bbdad5ae5b; range_end:; response_count:1; response_revision:5150; }","duration":"148.148195ms","start":"2026-10-09T09:23:19.015814Z","end":"2026-10-09T09:23:19.163962Z","steps":["trace[2123053699] 'range keys from in-memory index tree' (duration: 147.807977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:19.164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.123606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728699"} {"level":"info","ts":"2026-10-09T09:23:19.164052Z","caller":"traceutil/trace.go:171","msg":"trace[829186800] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5150; }","duration":"236.211068ms","start":"2026-10-09T09:23:18.927828Z","end":"2026-10-09T09:23:19.164039Z","steps":["trace[829186800] 'range keys from in-memory index tree' (duration: 235.8306ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:34.786133Z","caller":"traceutil/trace.go:171","msg":"trace[180817336] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5641; }","duration":"305.908134ms","start":"2026-10-09T09:23:34.480183Z","end":"2026-10-09T09:23:34.786091Z","steps":["trace[180817336] 'read index received' (duration: 264.472839ms)","trace[180817336] 'applied index is now lower than readState.Index' (duration: 41.433644ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:34.78617Z","caller":"traceutil/trace.go:171","msg":"trace[1575504349] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"337.53846ms","start":"2026-10-09T09:23:34.448604Z","end":"2026-10-09T09:23:34.786143Z","steps":["trace[1575504349] 'process raft request' (duration: 296.120585ms)","trace[1575504349] 'compare' (duration: 41.132768ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:23:34.786408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:23:34.44856Z","time spent":"337.727244ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T09:23:34.786832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.637739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:23:34.786904Z","caller":"traceutil/trace.go:171","msg":"trace[457392954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5438; }","duration":"306.762882ms","start":"2026-10-09T09:23:34.480123Z","end":"2026-10-09T09:23:34.786886Z","steps":["trace[457392954] 'agreement among raft nodes before linearized reading' (duration: 306.098237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:34.786954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:23:34.480103Z","time spent":"306.839864ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T09:23:34.800891Z","caller":"traceutil/trace.go:171","msg":"trace[1882893568] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"208.614043ms","start":"2026-10-09T09:23:34.592252Z","end":"2026-10-09T09:23:34.800866Z","steps":["trace[1882893568] 'process raft request' (duration: 208.436929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:34.801117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.614196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-vqv8r\" ","response":"range_response_count:1 size:7666"} {"level":"info","ts":"2026-10-09T09:23:34.8012Z","caller":"traceutil/trace.go:171","msg":"trace[558911814] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-vqv8r; range_end:; response_count:1; response_revision:5439; }","duration":"131.738008ms","start":"2026-10-09T09:23:34.669439Z","end":"2026-10-09T09:23:34.801177Z","steps":["trace[558911814] 'agreement among raft nodes before linearized reading' (duration: 131.509483ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:35.04108Z","caller":"traceutil/trace.go:171","msg":"trace[66389574] linearizableReadLoop","detail":"{readStateIndex:5644; appliedIndex:5643; }","duration":"209.959313ms","start":"2026-10-09T09:23:34.831097Z","end":"2026-10-09T09:23:35.041057Z","steps":["trace[66389574] 'read index received' (duration: 209.7913ms)","trace[66389574] 'applied index is now lower than readState.Index' (duration: 166.613µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:35.04115Z","caller":"traceutil/trace.go:171","msg":"trace[29754618] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"228.840515ms","start":"2026-10-09T09:23:34.81228Z","end":"2026-10-09T09:23:35.04112Z","steps":["trace[29754618] 'process raft request' (duration: 228.6365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:35.041378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.26692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T09:23:35.04144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.238794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-09T09:23:35.041467Z","caller":"traceutil/trace.go:171","msg":"trace[2147354374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5440; }","duration":"210.414473ms","start":"2026-10-09T09:23:34.83103Z","end":"2026-10-09T09:23:35.041445Z","steps":["trace[2147354374] 'agreement among raft nodes before linearized reading' (duration: 210.119036ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:35.041489Z","caller":"traceutil/trace.go:171","msg":"trace[173901734] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5440; }","duration":"173.326776ms","start":"2026-10-09T09:23:34.868149Z","end":"2026-10-09T09:23:35.041476Z","steps":["trace[173901734] 'agreement among raft nodes before linearized reading' (duration: 173.058031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:42.724947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.796616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:23:42.725071Z","caller":"traceutil/trace.go:171","msg":"trace[705317155] linearizableReadLoop","detail":"{readStateIndex:5837; appliedIndex:5836; }","duration":"140.987663ms","start":"2026-10-09T09:23:42.584065Z","end":"2026-10-09T09:23:42.725052Z","steps":["trace[705317155] 'read index received' (duration: 63.372µs)","trace[705317155] 'applied index is now lower than readState.Index' (duration: 140.923021ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:23:42.725213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.155266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77c0cb00-29cc-44df-a242-c86534c84de3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-09T09:23:42.725245Z","caller":"traceutil/trace.go:171","msg":"trace[227267468] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77c0cb00-29cc-44df-a242-c86534c84de3; range_end:; response_count:1; response_revision:5628; }","duration":"141.228768ms","start":"2026-10-09T09:23:42.584007Z","end":"2026-10-09T09:23:42.725235Z","steps":["trace[227267468] 'agreement among raft nodes before linearized reading' (duration: 141.091035ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:42.725261Z","caller":"traceutil/trace.go:171","msg":"trace[1040837113] transaction","detail":"{read_only:false; response_revision:5628; number_of_response:1; }","duration":"239.291063ms","start":"2026-10-09T09:23:42.485794Z","end":"2026-10-09T09:23:42.725085Z","steps":["trace[1040837113] 'process raft request' (duration: 55.2002ms)","trace[1040837113] 'compare' (duration: 183.659574ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:42.989412Z","caller":"traceutil/trace.go:171","msg":"trace[756284104] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"211.228452ms","start":"2026-10-09T09:23:42.778132Z","end":"2026-10-09T09:23:42.989361Z","steps":["trace[756284104] 'process raft request' (duration: 211.047468ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:42.998156Z","caller":"traceutil/trace.go:171","msg":"trace[1961536276] linearizableReadLoop","detail":"{readStateIndex:5839; appliedIndex:5837; }","duration":"152.458726ms","start":"2026-10-09T09:23:42.845679Z","end":"2026-10-09T09:23:42.998138Z","steps":["trace[1961536276] 'read index received' (duration: 143.505235ms)","trace[1961536276] 'applied index is now lower than readState.Index' (duration: 8.952431ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:42.998254Z","caller":"traceutil/trace.go:171","msg":"trace[785128083] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"186.8228ms","start":"2026-10-09T09:23:42.811401Z","end":"2026-10-09T09:23:42.998224Z","steps":["trace[785128083] 'process raft request' (duration: 186.660557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:42.998344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.655701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z2gnq\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-10-09T09:23:42.998382Z","caller":"traceutil/trace.go:171","msg":"trace[1095268488] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z2gnq; range_end:; response_count:1; response_revision:5630; }","duration":"152.754333ms","start":"2026-10-09T09:23:42.845617Z","end":"2026-10-09T09:23:42.998371Z","steps":["trace[1095268488] 'agreement among raft nodes before linearized reading' (duration: 152.62016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:42.99837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.690624ms","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-10-09T09:23:42.998429Z","caller":"traceutil/trace.go:171","msg":"trace[2047119324] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5630; }","duration":"110.778246ms","start":"2026-10-09T09:23:42.887636Z","end":"2026-10-09T09:23:42.998414Z","steps":["trace[2047119324] 'agreement among raft nodes before linearized reading' (duration: 110.659203ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:24:21.931908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-10-09T09:24:22.013768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"80.299066ms","hash":3527954394,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":33017856,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T09:24:22.013837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3527954394,"revision":2992,"compact-revision":-1} {"level":"info","ts":"2026-10-09T09:29:21.940024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5925} {"level":"info","ts":"2026-10-09T09:29:22.024603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5925,"took":"82.768761ms","hash":3453445041,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":26193920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T09:29:22.024665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3453445041,"revision":5925,"compact-revision":2992} {"level":"warn","ts":"2026-10-09T09:30:02.179667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.554865ms","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-10-09T09:30:02.179756Z","caller":"traceutil/trace.go:171","msg":"trace[149037680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8489; }","duration":"231.688398ms","start":"2026-10-09T09:30:01.94805Z","end":"2026-10-09T09:30:02.179738Z","steps":["trace[149037680] 'range keys from in-memory index tree' (duration: 231.36456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:02.179749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.5731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7b66c44957-ksrql.18dcd27e5e1faf93\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-09T09:30:02.179845Z","caller":"traceutil/trace.go:171","msg":"trace[1537524445] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7b66c44957-ksrql.18dcd27e5e1faf93; range_end:; response_count:1; response_revision:8489; }","duration":"205.709453ms","start":"2026-10-09T09:30:01.9741Z","end":"2026-10-09T09:30:02.179809Z","steps":["trace[1537524445] 'range keys from in-memory index tree' (duration: 205.368245ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:02.296001Z","caller":"traceutil/trace.go:171","msg":"trace[1451270060] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"113.903519ms","start":"2026-10-09T09:30:02.182073Z","end":"2026-10-09T09:30:02.295976Z","steps":["trace[1451270060] 'process raft request' (duration: 113.735445ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:02.38378Z","caller":"traceutil/trace.go:171","msg":"trace[824997459] linearizableReadLoop","detail":"{readStateIndex:8791; appliedIndex:8788; }","duration":"138.249928ms","start":"2026-10-09T09:30:02.245507Z","end":"2026-10-09T09:30:02.383757Z","steps":["trace[824997459] 'read index received' (duration: 50.420043ms)","trace[824997459] 'applied index is now lower than readState.Index' (duration: 87.828845ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:30:02.383799Z","caller":"traceutil/trace.go:171","msg":"trace[792142741] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"200.052137ms","start":"2026-10-09T09:30:02.183722Z","end":"2026-10-09T09:30:02.383774Z","steps":["trace[792142741] 'process raft request' (duration: 199.085495ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:02.383895Z","caller":"traceutil/trace.go:171","msg":"trace[1361018524] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"191.507755ms","start":"2026-10-09T09:30:02.192355Z","end":"2026-10-09T09:30:02.383863Z","steps":["trace[1361018524] 'process raft request' (duration: 191.353872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:02.384002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.474493ms","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-10-09T09:30:02.384057Z","caller":"traceutil/trace.go:171","msg":"trace[2050261405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8492; }","duration":"138.542194ms","start":"2026-10-09T09:30:02.245501Z","end":"2026-10-09T09:30:02.384043Z","steps":["trace[2050261405] 'agreement among raft nodes before linearized reading' (duration: 138.369741ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:13.74374Z","caller":"traceutil/trace.go:171","msg":"trace[1114593778] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"148.108281ms","start":"2026-10-09T09:30:13.595581Z","end":"2026-10-09T09:30:13.743689Z","steps":["trace[1114593778] 'process raft request' (duration: 147.889596ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:20.211788Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022bf.wal"} {"level":"info","ts":"2026-10-09T09:30:43.397567Z","caller":"traceutil/trace.go:171","msg":"trace[528100503] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"103.232963ms","start":"2026-10-09T09:30:43.294274Z","end":"2026-10-09T09:30:43.397507Z","steps":["trace[528100503] 'process raft request' (duration: 102.992957ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:46.145582Z","caller":"traceutil/trace.go:171","msg":"trace[11368973] transaction","detail":"{read_only:false; response_revision:8781; number_of_response:1; }","duration":"109.571414ms","start":"2026-10-09T09:30:46.035985Z","end":"2026-10-09T09:30:46.145557Z","steps":["trace[11368973] 'process raft request' (duration: 109.38566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:53.626676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.950872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:30:53.626877Z","caller":"traceutil/trace.go:171","msg":"trace[2107909345] transaction","detail":"{read_only:false; response_revision:8823; number_of_response:1; }","duration":"139.356616ms","start":"2026-10-09T09:30:53.487479Z","end":"2026-10-09T09:30:53.626835Z","steps":["trace[2107909345] 'process raft request' (duration: 16.156169ms)","trace[2107909345] 'compare' (duration: 122.815919ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:30:56.566049Z","caller":"traceutil/trace.go:171","msg":"trace[1322404705] transaction","detail":"{read_only:false; response_revision:8847; number_of_response:1; }","duration":"111.134119ms","start":"2026-10-09T09:30:56.454891Z","end":"2026-10-09T09:30:56.566025Z","steps":["trace[1322404705] 'process raft request' (duration: 101.509985ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:56.615913Z","caller":"traceutil/trace.go:171","msg":"trace[1375798262] linearizableReadLoop","detail":"{readStateIndex:9161; appliedIndex:9159; }","duration":"151.799402ms","start":"2026-10-09T09:30:56.464088Z","end":"2026-10-09T09:30:56.615888Z","steps":["trace[1375798262] 'read index received' (duration: 92.485394ms)","trace[1375798262] 'applied index is now lower than readState.Index' (duration: 59.312868ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:30:56.615972Z","caller":"traceutil/trace.go:171","msg":"trace[264068058] transaction","detail":"{read_only:false; response_revision:8848; number_of_response:1; }","duration":"153.842647ms","start":"2026-10-09T09:30:56.462111Z","end":"2026-10-09T09:30:56.615954Z","steps":["trace[264068058] 'process raft request' (duration: 153.571462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:56.616149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.037827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-10-09T09:30:56.616153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.863532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:30:56.616191Z","caller":"traceutil/trace.go:171","msg":"trace[134447058] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8848; }","duration":"152.118729ms","start":"2026-10-09T09:30:56.464061Z","end":"2026-10-09T09:30:56.61618Z","steps":["trace[134447058] 'agreement among raft nodes before linearized reading' (duration: 151.960386ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:56.616222Z","caller":"traceutil/trace.go:171","msg":"trace[400259532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8848; }","duration":"137.974285ms","start":"2026-10-09T09:30:56.478229Z","end":"2026-10-09T09:30:56.616203Z","steps":["trace[400259532] 'agreement among raft nodes before linearized reading' (duration: 137.871403ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:32:20.509431Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T09:32:20.516731Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T09:32:20.516861Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T09:32:22.324757Z","caller":"traceutil/trace.go:171","msg":"trace[434474055] transaction","detail":"{read_only:false; response_revision:9676; number_of_response:1; }","duration":"114.337464ms","start":"2026-10-09T09:32:22.210383Z","end":"2026-10-09T09:32:22.324721Z","steps":["trace[434474055] 'process raft request' (duration: 113.968495ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:34:21.94714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8106} {"level":"info","ts":"2026-10-09T09:34:22.015202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8106,"took":"65.880488ms","hash":4101133770,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":28479488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T09:34:22.015283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4101133770,"revision":8106,"compact-revision":5925} {"level":"info","ts":"2026-10-09T09:39:21.954044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10513} {"level":"info","ts":"2026-10-09T09:39:22.041783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10513,"took":"85.339327ms","hash":4011864524,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":26038272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T09:39:22.041854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4011864524,"revision":10513,"compact-revision":8106} {"level":"info","ts":"2026-10-09T09:44:21.966413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12643} {"level":"info","ts":"2026-10-09T09:44:22.04976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12643,"took":"79.714355ms","hash":3563838800,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":27054080,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T09:44:22.049835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3563838800,"revision":12643,"compact-revision":10513} {"level":"info","ts":"2026-10-09T09:44:53.283824Z","caller":"traceutil/trace.go:171","msg":"trace[182849783] transaction","detail":"{read_only:false; response_revision:14957; number_of_response:1; }","duration":"119.635029ms","start":"2026-10-09T09:44:53.164167Z","end":"2026-10-09T09:44:53.283802Z","steps":["trace[182849783] 'process raft request' (duration: 119.506886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:44:53.520555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.695684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:44:53.520685Z","caller":"traceutil/trace.go:171","msg":"trace[1206891112] transaction","detail":"{read_only:false; response_revision:14958; number_of_response:1; }","duration":"267.338727ms","start":"2026-10-09T09:44:53.253309Z","end":"2026-10-09T09:44:53.520648Z","steps":["trace[1206891112] 'process raft request' (duration: 162.324556ms)","trace[1206891112] 'compare' (duration: 104.477839ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:44:53.520787Z","caller":"traceutil/trace.go:171","msg":"trace[1755234589] transaction","detail":"{read_only:false; response_revision:14959; number_of_response:1; }","duration":"152.947934ms","start":"2026-10-09T09:44:53.367822Z","end":"2026-10-09T09:44:53.520769Z","steps":["trace[1755234589] 'process raft request' (duration: 152.848182ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:44:53.520963Z","caller":"traceutil/trace.go:171","msg":"trace[54861261] linearizableReadLoop","detail":"{readStateIndex:15471; appliedIndex:15470; }","duration":"219.092026ms","start":"2026-10-09T09:44:53.301837Z","end":"2026-10-09T09:44:53.520929Z","steps":["trace[54861261] 'read index received' (duration: 113.810779ms)","trace[54861261] 'applied index is now lower than readState.Index' (duration: 105.277597ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:44:53.521215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.223332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T09:44:53.521256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.29275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-7659bd74f8-shq9r\" ","response":"range_response_count:1 size:6895"} {"level":"warn","ts":"2026-10-09T09:44:53.521244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.390583ms","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-10-09T09:44:53.521293Z","caller":"traceutil/trace.go:171","msg":"trace[347831254] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-7659bd74f8-shq9r; range_end:; response_count:1; response_revision:14959; }","duration":"198.360351ms","start":"2026-10-09T09:44:53.322922Z","end":"2026-10-09T09:44:53.521282Z","steps":["trace[347831254] 'agreement among raft nodes before linearized reading' (duration: 198.194207ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:44:53.521291Z","caller":"traceutil/trace.go:171","msg":"trace[929516210] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:14959; }","duration":"116.341544ms","start":"2026-10-09T09:44:53.404929Z","end":"2026-10-09T09:44:53.521271Z","steps":["trace[929516210] 'agreement among raft nodes before linearized reading' (duration: 116.179591ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:44:53.521319Z","caller":"traceutil/trace.go:171","msg":"trace[1660998428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14959; }","duration":"219.501605ms","start":"2026-10-09T09:44:53.301797Z","end":"2026-10-09T09:44:53.521299Z","steps":["trace[1660998428] 'agreement among raft nodes before linearized reading' (duration: 219.27329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:44:53.521243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.338789ms","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-10-09T09:44:53.521587Z","caller":"traceutil/trace.go:171","msg":"trace[244056780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14959; }","duration":"218.705008ms","start":"2026-10-09T09:44:53.302862Z","end":"2026-10-09T09:44:53.521567Z","steps":["trace[244056780] 'agreement among raft nodes before linearized reading' (duration: 218.240338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:46:23.90364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.487484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-10-09T09:46:23.903721Z","caller":"traceutil/trace.go:171","msg":"trace[1234369047] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15994; }","duration":"114.608467ms","start":"2026-10-09T09:46:23.789095Z","end":"2026-10-09T09:46:23.903703Z","steps":["trace[1234369047] 'range keys from in-memory index tree' (duration: 114.341371ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:49:21.972977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14668} {"level":"info","ts":"2026-10-09T09:49:22.054371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14668,"took":"78.687313ms","hash":3517393275,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T09:49:22.054446Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3517393275,"revision":14668,"compact-revision":12643} {"level":"info","ts":"2026-10-09T09:52:11.423691Z","caller":"traceutil/trace.go:171","msg":"trace[455621015] transaction","detail":"{read_only:false; response_revision:18526; number_of_response:1; }","duration":"166.784675ms","start":"2026-10-09T09:52:11.256882Z","end":"2026-10-09T09:52:11.423666Z","steps":["trace[455621015] 'process raft request' (duration: 166.625552ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:52:18.320737Z","caller":"traceutil/trace.go:171","msg":"trace[1986347241] linearizableReadLoop","detail":"{readStateIndex:19201; appliedIndex:19199; }","duration":"225.000063ms","start":"2026-10-09T09:52:18.095716Z","end":"2026-10-09T09:52:18.320716Z","steps":["trace[1986347241] 'read index received' (duration: 45.133754ms)","trace[1986347241] 'applied index is now lower than readState.Index' (duration: 179.865279ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:52:18.320951Z","caller":"traceutil/trace.go:171","msg":"trace[482581798] transaction","detail":"{read_only:false; response_revision:18570; number_of_response:1; }","duration":"277.210606ms","start":"2026-10-09T09:52:18.043729Z","end":"2026-10-09T09:52:18.32094Z","steps":["trace[482581798] 'process raft request' (duration: 270.990775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:52:18.321267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.541915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-w6n2w\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-10-09T09:52:18.321316Z","caller":"traceutil/trace.go:171","msg":"trace[645864848] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-w6n2w; range_end:; response_count:1; response_revision:18570; }","duration":"225.636568ms","start":"2026-10-09T09:52:18.095669Z","end":"2026-10-09T09:52:18.321306Z","steps":["trace[645864848] 'agreement among raft nodes before linearized reading' (duration: 225.466994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:52:18.32151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.068649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T09:52:18.321565Z","caller":"traceutil/trace.go:171","msg":"trace[1043795809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18570; }","duration":"117.166991ms","start":"2026-10-09T09:52:18.20439Z","end":"2026-10-09T09:52:18.321557Z","steps":["trace[1043795809] 'agreement among raft nodes before linearized reading' (duration: 117.059839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:52:18.321972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.124873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-pk79j\" ","response":"range_response_count:1 size:20661"} {"level":"info","ts":"2026-10-09T09:52:18.322011Z","caller":"traceutil/trace.go:171","msg":"trace[1287390140] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-pk79j; range_end:; response_count:1; response_revision:18570; }","duration":"114.185584ms","start":"2026-10-09T09:52:18.207816Z","end":"2026-10-09T09:52:18.322002Z","steps":["trace[1287390140] 'agreement among raft nodes before linearized reading' (duration: 114.097982ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:52:18.45159Z","caller":"traceutil/trace.go:171","msg":"trace[1222794402] transaction","detail":"{read_only:false; response_revision:18572; number_of_response:1; }","duration":"116.313941ms","start":"2026-10-09T09:52:18.335248Z","end":"2026-10-09T09:52:18.451562Z","steps":["trace[1222794402] 'process raft request' (duration: 115.964514ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:53:22.762388Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T09:53:22.770175Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T09:53:22.77052Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T09:54:21.980227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17246} {"level":"info","ts":"2026-10-09T09:54:22.083819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17246,"took":"100.077228ms","hash":4241537637,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":34103296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T09:54:22.083881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4241537637,"revision":17246,"compact-revision":14668} {"level":"info","ts":"2026-10-09T09:58:23.134458Z","caller":"traceutil/trace.go:171","msg":"trace[857541553] transaction","detail":"{read_only:false; response_revision:21371; number_of_response:1; }","duration":"151.274136ms","start":"2026-10-09T09:58:22.983149Z","end":"2026-10-09T09:58:23.134423Z","steps":["trace[857541553] 'process raft request' (duration: 151.042431ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:58:23.440318Z","caller":"traceutil/trace.go:171","msg":"trace[1963330445] transaction","detail":"{read_only:false; response_revision:21372; number_of_response:1; }","duration":"447.101256ms","start":"2026-10-09T09:58:22.993166Z","end":"2026-10-09T09:58:23.440268Z","steps":["trace[1963330445] 'process raft request' (duration: 355.567502ms)","trace[1963330445] 'compare' (duration: 91.187616ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:58:23.440503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:58:22.993138Z","time spent":"447.268151ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T09:58:24.372973Z","caller":"traceutil/trace.go:171","msg":"trace[1697507966] transaction","detail":"{read_only:false; response_revision:21375; number_of_response:1; }","duration":"200.799367ms","start":"2026-10-09T09:58:24.172127Z","end":"2026-10-09T09:58:24.372927Z","steps":["trace[1697507966] 'process raft request' (duration: 113.999108ms)","trace[1697507966] 'compare' (duration: 86.504482ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:58:24.706539Z","caller":"traceutil/trace.go:171","msg":"trace[1355897117] transaction","detail":"{read_only:false; response_revision:21378; number_of_response:1; }","duration":"285.174781ms","start":"2026-10-09T09:58:24.421331Z","end":"2026-10-09T09:58:24.706506Z","steps":["trace[1355897117] 'process raft request' (duration: 284.955136ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:58:24.706483Z","caller":"traceutil/trace.go:171","msg":"trace[416907762] linearizableReadLoop","detail":"{readStateIndex:22100; appliedIndex:22099; }","duration":"227.324663ms","start":"2026-10-09T09:58:24.479134Z","end":"2026-10-09T09:58:24.706458Z","steps":["trace[416907762] 'read index received' (duration: 227.142189ms)","trace[416907762] 'applied index is now lower than readState.Index' (duration: 181.264µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:58:24.706694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.533829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T09:58:24.706938Z","caller":"traceutil/trace.go:171","msg":"trace[390784399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21378; }","duration":"227.814244ms","start":"2026-10-09T09:58:24.479103Z","end":"2026-10-09T09:58:24.706917Z","steps":["trace[390784399] 'agreement among raft nodes before linearized reading' (duration: 227.526148ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:58:24.758135Z","caller":"traceutil/trace.go:171","msg":"trace[196802263] transaction","detail":"{read_only:false; response_revision:21379; number_of_response:1; }","duration":"185.700688ms","start":"2026-10-09T09:58:24.572409Z","end":"2026-10-09T09:58:24.758109Z","steps":["trace[196802263] 'process raft request' (duration: 185.33997ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:21.987321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19761} {"level":"info","ts":"2026-10-09T09:59:22.125429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19761,"took":"134.586541ms","hash":317449167,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":30056448,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T09:59:22.125601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":317449167,"revision":19761,"compact-revision":17246} {"level":"warn","ts":"2026-10-09T10:02:32.207571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.82764ms","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-10-09T10:02:32.207682Z","caller":"traceutil/trace.go:171","msg":"trace[1039590945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23966; }","duration":"145.984724ms","start":"2026-10-09T10:02:32.061672Z","end":"2026-10-09T10:02:32.207657Z","steps":["trace[1039590945] 'range keys from in-memory index tree' (duration: 145.672026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:02:32.208042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.982551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:02:32.208146Z","caller":"traceutil/trace.go:171","msg":"trace[1577965872] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:23966; }","duration":"132.135615ms","start":"2026-10-09T10:02:32.075987Z","end":"2026-10-09T10:02:32.208123Z","steps":["trace[1577965872] 'count revisions from in-memory index tree' (duration: 131.893269ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:04:11.34357Z","caller":"traceutil/trace.go:171","msg":"trace[1787740894] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"107.170965ms","start":"2026-10-09T10:04:11.236354Z","end":"2026-10-09T10:04:11.343525Z","steps":["trace[1787740894] 'process raft request' (duration: 106.732696ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:04:21.996637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21938} {"level":"info","ts":"2026-10-09T10:04:22.110881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21938,"took":"109.986489ms","hash":1377092166,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":44351488,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-09T10:04:22.110944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1377092166,"revision":21938,"compact-revision":19761} {"level":"info","ts":"2026-10-09T10:05:41.405973Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000683f.wal"} {"level":"warn","ts":"2026-10-09T10:06:11.474603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.387613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T10:06:11.47468Z","caller":"traceutil/trace.go:171","msg":"trace[196757990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26180; }","duration":"142.517556ms","start":"2026-10-09T10:06:11.33215Z","end":"2026-10-09T10:06:11.474667Z","steps":["trace[196757990] 'range keys from in-memory index tree' (duration: 142.240811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:12.100585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.169716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:12.100737Z","caller":"traceutil/trace.go:171","msg":"trace[1629444451] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"187.88511ms","start":"2026-10-09T10:06:11.91281Z","end":"2026-10-09T10:06:12.100695Z","steps":["trace[1629444451] 'process raft request' (duration: 54.526479ms)","trace[1629444451] 'compare' (duration: 133.066844ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:12.381424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.980714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:12.381633Z","caller":"traceutil/trace.go:171","msg":"trace[1772466864] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"225.054301ms","start":"2026-10-09T10:06:12.156544Z","end":"2026-10-09T10:06:12.381599Z","steps":["trace[1772466864] 'process raft request' (duration: 224.98777ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:12.381741Z","caller":"traceutil/trace.go:171","msg":"trace[1583267329] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"277.229868ms","start":"2026-10-09T10:06:12.104479Z","end":"2026-10-09T10:06:12.381708Z","steps":["trace[1583267329] 'process raft request' (duration: 130.857614ms)","trace[1583267329] 'compare' (duration: 145.81234ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:16.809217Z","caller":"traceutil/trace.go:171","msg":"trace[1704487805] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"109.1582ms","start":"2026-10-09T10:06:16.699991Z","end":"2026-10-09T10:06:16.809149Z","steps":["trace[1704487805] 'process raft request' (duration: 108.930355ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:17.047729Z","caller":"traceutil/trace.go:171","msg":"trace[1683118283] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"231.017984ms","start":"2026-10-09T10:06:16.816588Z","end":"2026-10-09T10:06:17.047606Z","steps":["trace[1683118283] 'process raft request' (duration: 230.802289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:17.220787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.524975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:17.220924Z","caller":"traceutil/trace.go:171","msg":"trace[1162161368] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"169.051819ms","start":"2026-10-09T10:06:17.051847Z","end":"2026-10-09T10:06:17.220898Z","steps":["trace[1162161368] 'process raft request' (duration: 31.30213ms)","trace[1162161368] 'compare' (duration: 137.383211ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:17.223009Z","caller":"traceutil/trace.go:171","msg":"trace[865898067] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"157.89238ms","start":"2026-10-09T10:06:17.065098Z","end":"2026-10-09T10:06:17.22299Z","steps":["trace[865898067] 'process raft request' (duration: 157.833839ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:17.223221Z","caller":"traceutil/trace.go:171","msg":"trace[1235237217] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"169.203144ms","start":"2026-10-09T10:06:17.053865Z","end":"2026-10-09T10:06:17.223068Z","steps":["trace[1235237217] 'process raft request' (duration: 168.959048ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:17.516179Z","caller":"traceutil/trace.go:171","msg":"trace[1538627686] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"230.278708ms","start":"2026-10-09T10:06:17.28588Z","end":"2026-10-09T10:06:17.516158Z","steps":["trace[1538627686] 'process raft request' (duration: 229.986522ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:17.516357Z","caller":"traceutil/trace.go:171","msg":"trace[836044225] linearizableReadLoop","detail":"{readStateIndex:27080; appliedIndex:27079; }","duration":"202.096618ms","start":"2026-10-09T10:06:17.313963Z","end":"2026-10-09T10:06:17.51606Z","steps":["trace[836044225] 'read index received' (duration: 201.829042ms)","trace[836044225] 'applied index is now lower than readState.Index' (duration: 265.986µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:17.51646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.477936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:17.516701Z","caller":"traceutil/trace.go:171","msg":"trace[26510355] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26227; }","duration":"202.780144ms","start":"2026-10-09T10:06:17.313895Z","end":"2026-10-09T10:06:17.516675Z","steps":["trace[26510355] 'agreement among raft nodes before linearized reading' (duration: 202.493136ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:17.693228Z","caller":"traceutil/trace.go:171","msg":"trace[1629371857] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"162.403931ms","start":"2026-10-09T10:06:17.5308Z","end":"2026-10-09T10:06:17.693204Z","steps":["trace[1629371857] 'process raft request' (duration: 162.35589ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:17.693284Z","caller":"traceutil/trace.go:171","msg":"trace[993480493] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"172.074957ms","start":"2026-10-09T10:06:17.521174Z","end":"2026-10-09T10:06:17.693249Z","steps":["trace[993480493] 'process raft request' (duration: 154.737509ms)","trace[993480493] 'compare' (duration: 17.123503ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:18.127898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.146213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:18.128001Z","caller":"traceutil/trace.go:171","msg":"trace[2064716985] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26231; }","duration":"157.274386ms","start":"2026-10-09T10:06:17.970698Z","end":"2026-10-09T10:06:18.127973Z","steps":["trace[2064716985] 'count revisions from in-memory index tree' (duration: 157.044401ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:18.277071Z","caller":"traceutil/trace.go:171","msg":"trace[1871521485] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"185.854334ms","start":"2026-10-09T10:06:18.091189Z","end":"2026-10-09T10:06:18.277043Z","steps":["trace[1871521485] 'process raft request' (duration: 185.685091ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:18.579251Z","caller":"traceutil/trace.go:171","msg":"trace[645101631] linearizableReadLoop","detail":"{readStateIndex:27086; appliedIndex:27085; }","duration":"225.100012ms","start":"2026-10-09T10:06:18.354102Z","end":"2026-10-09T10:06:18.579202Z","steps":["trace[645101631] 'read index received' (duration: 217.557134ms)","trace[645101631] 'applied index is now lower than readState.Index' (duration: 7.540988ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:18.579437Z","caller":"traceutil/trace.go:171","msg":"trace[1649825955] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"449.810416ms","start":"2026-10-09T10:06:18.129611Z","end":"2026-10-09T10:06:18.579421Z","steps":["trace[1649825955] 'process raft request' (duration: 442.118454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:18.579573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.442381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:18.579694Z","caller":"traceutil/trace.go:171","msg":"trace[70054073] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26233; }","duration":"225.579324ms","start":"2026-10-09T10:06:18.354088Z","end":"2026-10-09T10:06:18.579668Z","steps":["trace[70054073] 'agreement among raft nodes before linearized reading' (duration: 225.39475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:18.57959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:18.129568Z","time spent":"449.902138ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:18.589079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.875789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T10:06:18.589117Z","caller":"traceutil/trace.go:171","msg":"trace[1889300085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26234; }","duration":"178.940069ms","start":"2026-10-09T10:06:18.410167Z","end":"2026-10-09T10:06:18.589107Z","steps":["trace[1889300085] 'agreement among raft nodes before linearized reading' (duration: 178.812257ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:18.589316Z","caller":"traceutil/trace.go:171","msg":"trace[1529500492] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"179.281218ms","start":"2026-10-09T10:06:18.410026Z","end":"2026-10-09T10:06:18.589307Z","steps":["trace[1529500492] 'process raft request' (duration: 178.848618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:18.589421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.482988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:18.589444Z","caller":"traceutil/trace.go:171","msg":"trace[870295756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26234; }","duration":"109.545089ms","start":"2026-10-09T10:06:18.479893Z","end":"2026-10-09T10:06:18.589439Z","steps":["trace[870295756] 'agreement among raft nodes before linearized reading' (duration: 109.506998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:18.926071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.241082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:18.926173Z","caller":"traceutil/trace.go:171","msg":"trace[355075264] linearizableReadLoop","detail":"{readStateIndex:27088; appliedIndex:27087; }","duration":"113.189211ms","start":"2026-10-09T10:06:18.812967Z","end":"2026-10-09T10:06:18.926156Z","steps":["trace[355075264] 'read index received' (duration: 44.961µs)","trace[355075264] 'applied index is now lower than readState.Index' (duration: 113.14314ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:18.926241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.272552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:18.926268Z","caller":"traceutil/trace.go:171","msg":"trace[366155268] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26235; }","duration":"113.331764ms","start":"2026-10-09T10:06:18.812925Z","end":"2026-10-09T10:06:18.926257Z","steps":["trace[366155268] 'agreement among raft nodes before linearized reading' (duration: 113.274253ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:18.926272Z","caller":"traceutil/trace.go:171","msg":"trace[714508954] transaction","detail":"{read_only:false; response_revision:26235; number_of_response:1; }","duration":"331.994972ms","start":"2026-10-09T10:06:18.59425Z","end":"2026-10-09T10:06:18.926245Z","steps":["trace[714508954] 'process raft request' (duration: 150.467604ms)","trace[714508954] 'compare' (duration: 181.124979ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:18.926407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:18.594225Z","time spent":"332.108845ms","remote":"127.0.0.1:59440","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-10-09T10:06:18.958084Z","caller":"traceutil/trace.go:171","msg":"trace[1986178401] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"143.101249ms","start":"2026-10-09T10:06:18.814899Z","end":"2026-10-09T10:06:18.958Z","steps":["trace[1986178401] 'process raft request' (duration: 142.582398ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:19.434542Z","caller":"traceutil/trace.go:171","msg":"trace[1904257877] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"206.533278ms","start":"2026-10-09T10:06:19.227981Z","end":"2026-10-09T10:06:19.434515Z","steps":["trace[1904257877] 'process raft request' (duration: 206.333833ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:19.52317Z","caller":"traceutil/trace.go:171","msg":"trace[1064349780] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"293.985433ms","start":"2026-10-09T10:06:19.229162Z","end":"2026-10-09T10:06:19.523147Z","steps":["trace[1064349780] 'process raft request' (duration: 293.818769ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:19.523538Z","caller":"traceutil/trace.go:171","msg":"trace[338656334] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"292.259463ms","start":"2026-10-09T10:06:19.231265Z","end":"2026-10-09T10:06:19.523524Z","steps":["trace[338656334] 'process raft request' (duration: 291.838454ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:19.524203Z","caller":"traceutil/trace.go:171","msg":"trace[996324170] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"258.509238ms","start":"2026-10-09T10:06:19.265665Z","end":"2026-10-09T10:06:19.524174Z","steps":["trace[996324170] 'process raft request' (duration: 258.280293ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:19.524343Z","caller":"traceutil/trace.go:171","msg":"trace[2037460244] linearizableReadLoop","detail":"{readStateIndex:27094; appliedIndex:27090; }","duration":"237.378197ms","start":"2026-10-09T10:06:19.286934Z","end":"2026-10-09T10:06:19.524312Z","steps":["trace[2037460244] 'read index received' (duration: 147.432737ms)","trace[2037460244] 'applied index is now lower than readState.Index' (duration: 89.94327ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:19.524455Z","caller":"traceutil/trace.go:171","msg":"trace[1366421014] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"140.115812ms","start":"2026-10-09T10:06:19.384322Z","end":"2026-10-09T10:06:19.524437Z","steps":["trace[1366421014] 'process raft request' (duration: 139.794055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.749278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.326947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:19.749361Z","caller":"traceutil/trace.go:171","msg":"trace[460300677] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26242; }","duration":"462.47248ms","start":"2026-10-09T10:06:19.286867Z","end":"2026-10-09T10:06:19.749339Z","steps":["trace[460300677] 'agreement among raft nodes before linearized reading' (duration: 237.593631ms)","trace[460300677] 'count revisions from in-memory index tree' (duration: 224.761815ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:19.7494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.286845Z","time spent":"462.544552ms","remote":"127.0.0.1:44304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:19.74945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.026711ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:19.749578Z","caller":"traceutil/trace.go:171","msg":"trace[1853853862] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26242; }","duration":"153.169794ms","start":"2026-10-09T10:06:19.59639Z","end":"2026-10-09T10:06:19.74956Z","steps":["trace[1853853862] 'range keys from in-memory index tree' (duration: 153.009971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.749743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.079828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:19.749825Z","caller":"traceutil/trace.go:171","msg":"trace[1352221141] linearizableReadLoop","detail":"{readStateIndex:27096; appliedIndex:27095; }","duration":"225.41173ms","start":"2026-10-09T10:06:19.5244Z","end":"2026-10-09T10:06:19.749812Z","steps":["trace[1352221141] 'read index received' (duration: 87.110757ms)","trace[1352221141] 'applied index is now lower than readState.Index' (duration: 138.299843ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:19.749875Z","caller":"traceutil/trace.go:171","msg":"trace[255718905] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"227.870255ms","start":"2026-10-09T10:06:19.521997Z","end":"2026-10-09T10:06:19.749868Z","steps":["trace[255718905] 'process raft request' (duration: 89.585603ms)","trace[255718905] 'compare' (duration: 137.573176ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:19.749915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.015292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:19.74997Z","caller":"traceutil/trace.go:171","msg":"trace[1493923024] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26243; }","duration":"230.107364ms","start":"2026-10-09T10:06:19.519844Z","end":"2026-10-09T10:06:19.749951Z","steps":["trace[1493923024] 'agreement among raft nodes before linearized reading' (duration: 230.025592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.75006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.665504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:19.750087Z","caller":"traceutil/trace.go:171","msg":"trace[1255027635] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26243; }","duration":"458.787027ms","start":"2026-10-09T10:06:19.291292Z","end":"2026-10-09T10:06:19.750079Z","steps":["trace[1255027635] 'agreement among raft nodes before linearized reading' (duration: 458.730516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.750107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.29127Z","time spent":"458.830937ms","remote":"127.0.0.1:59130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-09T10:06:19.989996Z","caller":"traceutil/trace.go:171","msg":"trace[1325173881] linearizableReadLoop","detail":"{readStateIndex:27097; appliedIndex:27096; }","duration":"240.129277ms","start":"2026-10-09T10:06:19.749841Z","end":"2026-10-09T10:06:19.98997Z","steps":["trace[1325173881] 'read index received' (duration: 240.088526ms)","trace[1325173881] 'applied index is now lower than readState.Index' (duration: 39.601µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:19.990143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.643191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:19.990179Z","caller":"traceutil/trace.go:171","msg":"trace[298388173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26244; }","duration":"360.751366ms","start":"2026-10-09T10:06:19.629418Z","end":"2026-10-09T10:06:19.99017Z","steps":["trace[298388173] 'agreement among raft nodes before linearized reading' (duration: 360.670553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.990215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.629393Z","time spent":"360.815066ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T10:06:19.990276Z","caller":"traceutil/trace.go:171","msg":"trace[1187616166] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"291.279752ms","start":"2026-10-09T10:06:19.698965Z","end":"2026-10-09T10:06:19.990245Z","steps":["trace[1187616166] 'process raft request' (duration: 290.845532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.990208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.185817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T10:06:19.990376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.445729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419189"} {"level":"info","ts":"2026-10-09T10:06:19.990419Z","caller":"traceutil/trace.go:171","msg":"trace[1725638789] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26244; }","duration":"379.447224ms","start":"2026-10-09T10:06:19.610945Z","end":"2026-10-09T10:06:19.990392Z","steps":["trace[1725638789] 'agreement among raft nodes before linearized reading' (duration: 379.162646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.990491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.610923Z","time spent":"379.547415ms","remote":"127.0.0.1:59618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-10-09T10:06:19.990502Z","caller":"traceutil/trace.go:171","msg":"trace[1378410819] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26244; }","duration":"250.670494ms","start":"2026-10-09T10:06:19.739806Z","end":"2026-10-09T10:06:19.990477Z","steps":["trace[1378410819] 'agreement among raft nodes before linearized reading' (duration: 250.174902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:19.990942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.885798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T10:06:19.991024Z","caller":"traceutil/trace.go:171","msg":"trace[251290392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26244; }","duration":"293.169894ms","start":"2026-10-09T10:06:19.697832Z","end":"2026-10-09T10:06:19.991001Z","steps":["trace[251290392] 'agreement among raft nodes before linearized reading' (duration: 292.217222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:20.323036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.235521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:20.323344Z","caller":"traceutil/trace.go:171","msg":"trace[761869345] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"142.508694ms","start":"2026-10-09T10:06:20.180779Z","end":"2026-10-09T10:06:20.323288Z","steps":["trace[761869345] 'process raft request' (duration: 142.447643ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:20.323513Z","caller":"traceutil/trace.go:171","msg":"trace[1886347478] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"455.892171ms","start":"2026-10-09T10:06:19.867605Z","end":"2026-10-09T10:06:20.323498Z","steps":["trace[1886347478] 'process raft request' (duration: 181.104418ms)","trace[1886347478] 'compare' (duration: 274.116838ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:20.323688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.867575Z","time spent":"455.976303ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:20.323711Z","caller":"traceutil/trace.go:171","msg":"trace[317496611] linearizableReadLoop","detail":"{readStateIndex:27098; appliedIndex:27097; }","duration":"333.622289ms","start":"2026-10-09T10:06:19.990063Z","end":"2026-10-09T10:06:20.323685Z","steps":["trace[317496611] 'read index received' (duration: 58.663802ms)","trace[317496611] 'applied index is now lower than readState.Index' (duration: 274.956227ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:20.323747Z","caller":"traceutil/trace.go:171","msg":"trace[868653068] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"327.174994ms","start":"2026-10-09T10:06:19.996523Z","end":"2026-10-09T10:06:20.323698Z","steps":["trace[868653068] 'process raft request' (duration: 326.622481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:20.323898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.449971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:20.323919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.996497Z","time spent":"327.336447ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:20.323952Z","caller":"traceutil/trace.go:171","msg":"trace[891848499] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26247; }","duration":"446.555814ms","start":"2026-10-09T10:06:19.877376Z","end":"2026-10-09T10:06:20.323932Z","steps":["trace[891848499] 'agreement among raft nodes before linearized reading' (duration: 446.449051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:20.32393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.907845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:20.323989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:19.877355Z","time spent":"446.624585ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-10-09T10:06:20.324004Z","caller":"traceutil/trace.go:171","msg":"trace[589894838] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26247; }","duration":"275.031119ms","start":"2026-10-09T10:06:20.048953Z","end":"2026-10-09T10:06:20.323985Z","steps":["trace[589894838] 'agreement among raft nodes before linearized reading' (duration: 274.913576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:20.645043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.501052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-09T10:06:20.645127Z","caller":"traceutil/trace.go:171","msg":"trace[149874462] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26247; }","duration":"217.625665ms","start":"2026-10-09T10:06:20.42748Z","end":"2026-10-09T10:06:20.645106Z","steps":["trace[149874462] 'range keys from in-memory index tree' (duration: 217.330558ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:20.668992Z","caller":"traceutil/trace.go:171","msg":"trace[1484281211] linearizableReadLoop","detail":"{readStateIndex:27101; appliedIndex:27100; }","duration":"191.625395ms","start":"2026-10-09T10:06:20.477339Z","end":"2026-10-09T10:06:20.668965Z","steps":["trace[1484281211] 'read index received' (duration: 191.162275ms)","trace[1484281211] 'applied index is now lower than readState.Index' (duration: 461.85µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:20.669049Z","caller":"traceutil/trace.go:171","msg":"trace[440080573] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"226.400981ms","start":"2026-10-09T10:06:20.442624Z","end":"2026-10-09T10:06:20.669025Z","steps":["trace[440080573] 'process raft request' (duration: 226.031883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:20.669125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.768208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:20.66916Z","caller":"traceutil/trace.go:171","msg":"trace[443922352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26248; }","duration":"191.86388ms","start":"2026-10-09T10:06:20.477285Z","end":"2026-10-09T10:06:20.669149Z","steps":["trace[443922352] 'agreement among raft nodes before linearized reading' (duration: 191.781198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:21.086315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.511332ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-09T10:06:21.086565Z","caller":"traceutil/trace.go:171","msg":"trace[2131581875] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"122.992519ms","start":"2026-10-09T10:06:20.96356Z","end":"2026-10-09T10:06:21.086553Z","steps":["trace[2131581875] 'process raft request' (duration: 122.966459ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:21.086648Z","caller":"traceutil/trace.go:171","msg":"trace[1532240247] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"492.299836ms","start":"2026-10-09T10:06:20.594285Z","end":"2026-10-09T10:06:21.086585Z","steps":["trace[1532240247] 'process raft request' (duration: 492.208954ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:21.086667Z","caller":"traceutil/trace.go:171","msg":"trace[1656749530] linearizableReadLoop","detail":"{readStateIndex:27104; appliedIndex:27101; }","duration":"157.47578ms","start":"2026-10-09T10:06:20.929161Z","end":"2026-10-09T10:06:21.086636Z","steps":["trace[1656749530] 'read index received' (duration: 40.361µs)","trace[1656749530] 'applied index is now lower than readState.Index' (duration: 157.433099ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:21.086684Z","caller":"traceutil/trace.go:171","msg":"trace[310104976] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"501.396889ms","start":"2026-10-09T10:06:20.585193Z","end":"2026-10-09T10:06:21.08659Z","steps":["trace[310104976] 'process raft request' (duration: 501.202955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:21.086801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:20.594263Z","time spent":"492.460409ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:21.086883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.711086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T10:06:21.086919Z","caller":"traceutil/trace.go:171","msg":"trace[1199231920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26251; }","duration":"157.798537ms","start":"2026-10-09T10:06:20.929109Z","end":"2026-10-09T10:06:21.086908Z","steps":["trace[1199231920] 'agreement among raft nodes before linearized reading' (duration: 157.618323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:21.086991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:20.58516Z","time spent":"501.666375ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:21.445119Z","caller":"traceutil/trace.go:171","msg":"trace[1217337649] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"293.929711ms","start":"2026-10-09T10:06:21.15114Z","end":"2026-10-09T10:06:21.44507Z","steps":["trace[1217337649] 'process raft request' (duration: 293.762997ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:21.55602Z","caller":"traceutil/trace.go:171","msg":"trace[487908059] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"376.410814ms","start":"2026-10-09T10:06:21.179588Z","end":"2026-10-09T10:06:21.555998Z","steps":["trace[487908059] 'process raft request' (duration: 374.256277ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:21.556087Z","caller":"traceutil/trace.go:171","msg":"trace[727746744] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"116.30906ms","start":"2026-10-09T10:06:21.439746Z","end":"2026-10-09T10:06:21.556055Z","steps":["trace[727746744] 'process raft request' (duration: 115.925761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:21.556191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:21.179566Z","time spent":"376.507537ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:21.808657Z","caller":"traceutil/trace.go:171","msg":"trace[1979335971] transaction","detail":"{read_only:false; response_revision:26258; number_of_response:1; }","duration":"247.920993ms","start":"2026-10-09T10:06:21.560714Z","end":"2026-10-09T10:06:21.808635Z","steps":["trace[1979335971] 'process raft request' (duration: 247.761749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:22.050962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.337076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:22.051182Z","caller":"traceutil/trace.go:171","msg":"trace[1753057916] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"489.78993ms","start":"2026-10-09T10:06:21.561369Z","end":"2026-10-09T10:06:22.051159Z","steps":["trace[1753057916] 'process raft request' (duration: 345.162747ms)","trace[1753057916] 'compare' (duration: 143.818215ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:22.051375Z","caller":"traceutil/trace.go:171","msg":"trace[466482158] linearizableReadLoop","detail":"{readStateIndex:27114; appliedIndex:27112; }","duration":"187.176665ms","start":"2026-10-09T10:06:21.864167Z","end":"2026-10-09T10:06:22.051344Z","steps":["trace[466482158] 'read index received' (duration: 42.335978ms)","trace[466482158] 'applied index is now lower than readState.Index' (duration: 144.838587ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:22.051497Z","caller":"traceutil/trace.go:171","msg":"trace[866791961] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"297.073841ms","start":"2026-10-09T10:06:21.754388Z","end":"2026-10-09T10:06:22.051462Z","steps":["trace[866791961] 'process raft request' (duration: 296.675752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:22.051546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:21.561349Z","time spent":"489.870451ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:22.052733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.542776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T10:06:22.052786Z","caller":"traceutil/trace.go:171","msg":"trace[1618017369] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26260; }","duration":"188.645098ms","start":"2026-10-09T10:06:21.864129Z","end":"2026-10-09T10:06:22.052774Z","steps":["trace[1618017369] 'agreement among raft nodes before linearized reading' (duration: 187.319788ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:32.575443Z","caller":"traceutil/trace.go:171","msg":"trace[153141663] linearizableReadLoop","detail":"{readStateIndex:27193; appliedIndex:27192; }","duration":"193.937735ms","start":"2026-10-09T10:06:32.381472Z","end":"2026-10-09T10:06:32.575409Z","steps":["trace[153141663] 'read index received' (duration: 193.656359ms)","trace[153141663] 'applied index is now lower than readState.Index' (duration: 279.076µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:32.575494Z","caller":"traceutil/trace.go:171","msg":"trace[60245805] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"203.301185ms","start":"2026-10-09T10:06:32.372159Z","end":"2026-10-09T10:06:32.57546Z","steps":["trace[60245805] 'process raft request' (duration: 202.971958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:32.575747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.245912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T10:06:32.575809Z","caller":"traceutil/trace.go:171","msg":"trace[1698771929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26337; }","duration":"194.353055ms","start":"2026-10-09T10:06:32.381437Z","end":"2026-10-09T10:06:32.57579Z","steps":["trace[1698771929] 'agreement among raft nodes before linearized reading' (duration: 194.13638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.050867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.769073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:33.050975Z","caller":"traceutil/trace.go:171","msg":"trace[30028305] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"470.932407ms","start":"2026-10-09T10:06:32.580022Z","end":"2026-10-09T10:06:33.050954Z","steps":["trace[30028305] 'process raft request' (duration: 110.9775ms)","trace[30028305] 'compare' (duration: 359.462787ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:33.051045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:32.580002Z","time spent":"471.01437ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:33.085999Z","caller":"traceutil/trace.go:171","msg":"trace[1942547802] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"213.477942ms","start":"2026-10-09T10:06:32.872505Z","end":"2026-10-09T10:06:33.085983Z","steps":["trace[1942547802] 'process raft request' (duration: 213.37068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.706159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.10341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:33.706276Z","caller":"traceutil/trace.go:171","msg":"trace[778923107] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"577.651163ms","start":"2026-10-09T10:06:33.128602Z","end":"2026-10-09T10:06:33.706253Z","steps":["trace[778923107] 'process raft request' (duration: 179.364409ms)","trace[778923107] 'compare' (duration: 397.545577ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:33.706356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.128569Z","time spent":"577.745925ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:35.083894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.7065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:35.084372Z","caller":"traceutil/trace.go:171","msg":"trace[259276566] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"981.003932ms","start":"2026-10-09T10:06:34.103344Z","end":"2026-10-09T10:06:35.084348Z","steps":["trace[259276566] 'process raft request' (duration: 980.944721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.084479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.103332Z","time spent":"981.097344ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:35.084717Z","caller":"traceutil/trace.go:171","msg":"trace[401336061] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"1.79149161s","start":"2026-10-09T10:06:33.293188Z","end":"2026-10-09T10:06:35.08468Z","steps":["trace[401336061] 'process raft request' (duration: 1.790887927s)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.08476Z","caller":"traceutil/trace.go:171","msg":"trace[1570850283] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"981.781249ms","start":"2026-10-09T10:06:34.102951Z","end":"2026-10-09T10:06:35.084732Z","steps":["trace[1570850283] 'process raft request' (duration: 981.289678ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.084743Z","caller":"traceutil/trace.go:171","msg":"trace[1048424086] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"1.475443605s","start":"2026-10-09T10:06:33.609263Z","end":"2026-10-09T10:06:35.084707Z","steps":["trace[1048424086] 'process raft request' (duration: 1.474927864s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.084902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.293158Z","time spent":"1.791652183s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:35.084946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.102925Z","time spent":"981.932212ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:35.084956Z","caller":"traceutil/trace.go:171","msg":"trace[294212598] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"1.944599032s","start":"2026-10-09T10:06:33.140329Z","end":"2026-10-09T10:06:35.084928Z","steps":["trace[294212598] 'process raft request' (duration: 1.006766296s)","trace[294212598] 'compare' (duration: 936.141758ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.085027Z","caller":"traceutil/trace.go:171","msg":"trace[1981977899] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"1.477530152s","start":"2026-10-09T10:06:33.607474Z","end":"2026-10-09T10:06:35.085004Z","steps":["trace[1981977899] 'process raft request' (duration: 1.476667953s)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.08507Z","caller":"traceutil/trace.go:171","msg":"trace[401158449] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"1.941556944s","start":"2026-10-09T10:06:33.143483Z","end":"2026-10-09T10:06:35.08504Z","steps":["trace[401158449] 'process raft request' (duration: 1.940513371s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.085138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.607446Z","time spent":"1.477638834s","remote":"127.0.0.1:59440","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-10-09T10:06:35.085208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.143453Z","time spent":"1.941688707s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:35.085068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.609241Z","time spent":"1.475661369s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:35.085092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.140298Z","time spent":"1.944723005s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:35.085202Z","caller":"traceutil/trace.go:171","msg":"trace[522794594] linearizableReadLoop","detail":"{readStateIndex:27198; appliedIndex:27195; }","duration":"1.886068704s","start":"2026-10-09T10:06:33.199031Z","end":"2026-10-09T10:06:35.085099Z","steps":["trace[522794594] 'read index received' (duration: 108.951306ms)","trace[522794594] 'applied index is now lower than readState.Index' (duration: 1.777114228s)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.085788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.886751639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T10:06:35.085846Z","caller":"traceutil/trace.go:171","msg":"trace[550735912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26347; }","duration":"1.886859371s","start":"2026-10-09T10:06:33.198967Z","end":"2026-10-09T10:06:35.085827Z","steps":["trace[550735912] 'agreement among raft nodes before linearized reading' (duration: 1.886528373s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.085894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.198946Z","time spent":"1.886934043s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T10:06:35.478778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999862967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-10-09T10:06:35.478863Z","caller":"traceutil/trace.go:171","msg":"trace[72089234] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00000783s","start":"2026-10-09T10:06:33.478837Z","end":"2026-10-09T10:06:35.478845Z","steps":["trace[72089234] 'agreement among raft nodes before linearized reading' (duration: 1.999899798s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.479001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.47881Z","time spent":"2.000089261s","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T10:06:35.54381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.686168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:35.544026Z","caller":"traceutil/trace.go:171","msg":"trace[257278439] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"963.594752ms","start":"2026-10-09T10:06:34.580399Z","end":"2026-10-09T10:06:35.543993Z","steps":["trace[257278439] 'process raft request' (duration: 647.599887ms)","trace[257278439] 'compare' (duration: 315.489403ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.544107Z","caller":"traceutil/trace.go:171","msg":"trace[36454016] linearizableReadLoop","detail":"{readStateIndex:27204; appliedIndex:27203; }","duration":"458.616173ms","start":"2026-10-09T10:06:35.085467Z","end":"2026-10-09T10:06:35.544083Z","steps":["trace[36454016] 'read index received' (duration: 142.502685ms)","trace[36454016] 'applied index is now lower than readState.Index' (duration: 316.110767ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.544157Z","caller":"traceutil/trace.go:171","msg":"trace[349266017] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"214.562498ms","start":"2026-10-09T10:06:35.329575Z","end":"2026-10-09T10:06:35.544138Z","steps":["trace[349266017] 'process raft request' (duration: 214.494336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.58037Z","time spent":"963.735285ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:35.544293Z","caller":"traceutil/trace.go:171","msg":"trace[2071479990] transaction","detail":"{read_only:false; response_revision:26349; number_of_response:1; }","duration":"453.393576ms","start":"2026-10-09T10:06:35.090889Z","end":"2026-10-09T10:06:35.544282Z","steps":["trace[2071479990] 'process raft request' (duration: 453.063329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.91585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-09T10:06:35.544346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:35.090864Z","time spent":"453.455018ms","remote":"127.0.0.1:59440","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-10-09T10:06:35.544377Z","caller":"traceutil/trace.go:171","msg":"trace[1267982716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26350; }","duration":"488.988922ms","start":"2026-10-09T10:06:35.055376Z","end":"2026-10-09T10:06:35.544365Z","steps":["trace[1267982716] 'agreement among raft nodes before linearized reading' (duration: 488.817358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.54438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839091005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T10:06:35.544408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:35.055356Z","time spent":"489.044393ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-09T10:06:35.544439Z","caller":"traceutil/trace.go:171","msg":"trace[1468109761] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26350; }","duration":"1.839180156s","start":"2026-10-09T10:06:33.705244Z","end":"2026-10-09T10:06:35.544424Z","steps":["trace[1468109761] 'agreement among raft nodes before linearized reading' (duration: 1.839068914s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29004856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:35.544435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.31902ms","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:6"} {"level":"warn","ts":"2026-10-09T10:06:35.54446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851622794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:35.544507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679756132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-09T10:06:35.544514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.037453878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-09T10:06:35.544531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.117094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:35.54452Z","caller":"traceutil/trace.go:171","msg":"trace[1426196770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:26350; }","duration":"1.290196794s","start":"2026-10-09T10:06:34.254301Z","end":"2026-10-09T10:06:35.544498Z","steps":["trace[1426196770] 'agreement among raft nodes before linearized reading' (duration: 1.29001691s)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.544535Z","caller":"traceutil/trace.go:171","msg":"trace[2041334206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26350; }","duration":"1.679821693s","start":"2026-10-09T10:06:33.864705Z","end":"2026-10-09T10:06:35.544526Z","steps":["trace[2041334206] 'agreement among raft nodes before linearized reading' (duration: 1.679705411s)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.544535Z","caller":"traceutil/trace.go:171","msg":"trace[1219946375] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26350; }","duration":"570.479404ms","start":"2026-10-09T10:06:34.974027Z","end":"2026-10-09T10:06:35.544506Z","steps":["trace[1219946375] 'agreement among raft nodes before linearized reading' (duration: 570.31306ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.544543Z","caller":"traceutil/trace.go:171","msg":"trace[842196662] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26350; }","duration":"1.851761897s","start":"2026-10-09T10:06:33.692761Z","end":"2026-10-09T10:06:35.544522Z","steps":["trace[842196662] 'agreement among raft nodes before linearized reading' (duration: 1.851630914s)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.54456Z","caller":"traceutil/trace.go:171","msg":"trace[1476591137] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:26350; }","duration":"370.185166ms","start":"2026-10-09T10:06:35.174367Z","end":"2026-10-09T10:06:35.544552Z","steps":["trace[1476591137] 'agreement among raft nodes before linearized reading' (duration: 370.129485ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.54456Z","caller":"traceutil/trace.go:171","msg":"trace[1506427599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26350; }","duration":"2.03753554s","start":"2026-10-09T10:06:33.507009Z","end":"2026-10-09T10:06:35.544545Z","steps":["trace[1506427599] 'agreement among raft nodes before linearized reading' (duration: 2.037409697s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.86468Z","time spent":"1.679885425s","remote":"127.0.0.1:59440","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":"warn","ts":"2026-10-09T10:06:35.544587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:35.174345Z","time spent":"370.235436ms","remote":"127.0.0.1:49532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.544588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.254281Z","time spent":"1.290287705s","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.544485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.705233Z","time spent":"1.839241707s","remote":"127.0.0.1:59346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.544606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.506987Z","time spent":"2.037610361s","remote":"127.0.0.1:59440","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-10-09T10:06:35.544636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.974001Z","time spent":"570.575116ms","remote":"127.0.0.1:48578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.544649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.223139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-09T10:06:35.544707Z","caller":"traceutil/trace.go:171","msg":"trace[679817336] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26350; }","duration":"579.343022ms","start":"2026-10-09T10:06:34.965344Z","end":"2026-10-09T10:06:35.544687Z","steps":["trace[679817336] 'agreement among raft nodes before linearized reading' (duration: 579.123007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147356476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:35.544593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.692737Z","time spent":"1.851843298s","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-09T10:06:35.544747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.96532Z","time spent":"579.416364ms","remote":"127.0.0.1:59322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-10-09T10:06:35.544754Z","caller":"traceutil/trace.go:171","msg":"trace[336008282] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26350; }","duration":"2.147782075s","start":"2026-10-09T10:06:33.396956Z","end":"2026-10-09T10:06:35.544738Z","steps":["trace[336008282] 'agreement among raft nodes before linearized reading' (duration: 2.147358116s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.396932Z","time spent":"2.147850606s","remote":"127.0.0.1:44686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.54483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.585114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-09T10:06:35.544474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434393897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T10:06:35.544856Z","caller":"traceutil/trace.go:171","msg":"trace[1711288622] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:26350; }","duration":"149.645335ms","start":"2026-10-09T10:06:35.395203Z","end":"2026-10-09T10:06:35.544849Z","steps":["trace[1711288622] 'agreement among raft nodes before linearized reading' (duration: 149.563604ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.544889Z","caller":"traceutil/trace.go:171","msg":"trace[692863681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26350; }","duration":"1.434856917s","start":"2026-10-09T10:06:34.110008Z","end":"2026-10-09T10:06:35.544865Z","steps":["trace[692863681] 'agreement among raft nodes before linearized reading' (duration: 1.434342765s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.544948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.109996Z","time spent":"1.434932828s","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T10:06:35.845799Z","caller":"traceutil/trace.go:171","msg":"trace[1245369341] transaction","detail":"{read_only:false; response_revision:26351; number_of_response:1; }","duration":"295.530836ms","start":"2026-10-09T10:06:35.550235Z","end":"2026-10-09T10:06:35.845765Z","steps":["trace[1245369341] 'process raft request' (duration: 295.181718ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.845854Z","caller":"traceutil/trace.go:171","msg":"trace[2079290381] linearizableReadLoop","detail":"{readStateIndex:27208; appliedIndex:27206; }","duration":"295.474135ms","start":"2026-10-09T10:06:35.550363Z","end":"2026-10-09T10:06:35.845837Z","steps":["trace[2079290381] 'read index received' (duration: 17.527651ms)","trace[2079290381] 'applied index is now lower than readState.Index' (duration: 277.945374ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.845938Z","caller":"traceutil/trace.go:171","msg":"trace[1465198281] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"294.970454ms","start":"2026-10-09T10:06:35.550954Z","end":"2026-10-09T10:06:35.845924Z","steps":["trace[1465198281] 'process raft request' (duration: 294.598845ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.846226Z","caller":"traceutil/trace.go:171","msg":"trace[437920682] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"291.861126ms","start":"2026-10-09T10:06:35.554347Z","end":"2026-10-09T10:06:35.846208Z","steps":["trace[437920682] 'process raft request' (duration: 291.434655ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:35.846305Z","caller":"traceutil/trace.go:171","msg":"trace[874554676] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"295.187969ms","start":"2026-10-09T10:06:35.551089Z","end":"2026-10-09T10:06:35.846277Z","steps":["trace[874554676] 'process raft request' (duration: 294.501733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.846608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.222853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fce3ab71-eae8-4b8b-87e7-263fa0e54bbb\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-10-09T10:06:35.846952Z","caller":"traceutil/trace.go:171","msg":"trace[1607318724] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fce3ab71-eae8-4b8b-87e7-263fa0e54bbb; range_end:; response_count:1; response_revision:26354; }","duration":"296.571441ms","start":"2026-10-09T10:06:35.550354Z","end":"2026-10-09T10:06:35.846926Z","steps":["trace[1607318724] 'agreement among raft nodes before linearized reading' (duration: 296.115191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.885125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.310949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:35.885285Z","caller":"traceutil/trace.go:171","msg":"trace[1292579567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26356; }","duration":"328.509765ms","start":"2026-10-09T10:06:35.556758Z","end":"2026-10-09T10:06:35.885268Z","steps":["trace[1292579567] 'agreement among raft nodes before linearized reading' (duration: 328.289699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.885378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:35.556743Z","time spent":"328.623337ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T10:06:35.885159Z","caller":"traceutil/trace.go:171","msg":"trace[581145261] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"316.130738ms","start":"2026-10-09T10:06:35.568993Z","end":"2026-10-09T10:06:35.885124Z","steps":["trace[581145261] 'process raft request' (duration: 315.875221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.885564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.46292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-09T10:06:35.885594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.393275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:35.885652Z","caller":"traceutil/trace.go:171","msg":"trace[85635234] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26356; }","duration":"301.570572ms","start":"2026-10-09T10:06:35.584035Z","end":"2026-10-09T10:06:35.885606Z","steps":["trace[85635234] 'agreement among raft nodes before linearized reading' (duration: 301.382548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.885699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:35.584017Z","time spent":"301.668124ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-10-09T10:06:35.885712Z","caller":"traceutil/trace.go:171","msg":"trace[170809954] transaction","detail":"{read_only:false; response_revision:26356; number_of_response:1; }","duration":"173.742064ms","start":"2026-10-09T10:06:35.711948Z","end":"2026-10-09T10:06:35.885691Z","steps":["trace[170809954] 'process raft request' (duration: 173.047488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.885808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:35.568968Z","time spent":"316.632908ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:35.886725Z","caller":"traceutil/trace.go:171","msg":"trace[1054103018] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26356; }","duration":"168.522658ms","start":"2026-10-09T10:06:35.717145Z","end":"2026-10-09T10:06:35.885668Z","steps":["trace[1054103018] 'agreement among raft nodes before linearized reading' (duration: 168.020577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:36.189391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.325299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:36.189517Z","caller":"traceutil/trace.go:171","msg":"trace[1507363722] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26357; }","duration":"166.445291ms","start":"2026-10-09T10:06:36.023006Z","end":"2026-10-09T10:06:36.189451Z","steps":["trace[1507363722] 'range keys from in-memory index tree' (duration: 166.258846ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:38.116232Z","caller":"traceutil/trace.go:171","msg":"trace[816859594] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"229.385598ms","start":"2026-10-09T10:06:37.886815Z","end":"2026-10-09T10:06:38.116201Z","steps":["trace[816859594] 'process raft request' (duration: 228.477888ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:38.116279Z","caller":"traceutil/trace.go:171","msg":"trace[1002437524] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"213.948233ms","start":"2026-10-09T10:06:37.902295Z","end":"2026-10-09T10:06:38.116244Z","steps":["trace[1002437524] 'process raft request' (duration: 213.3481ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:38.116516Z","caller":"traceutil/trace.go:171","msg":"trace[1449910942] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"224.141221ms","start":"2026-10-09T10:06:37.892363Z","end":"2026-10-09T10:06:38.116505Z","steps":["trace[1449910942] 'process raft request' (duration: 223.22595ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:38.116558Z","caller":"traceutil/trace.go:171","msg":"trace[1438881513] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"228.205131ms","start":"2026-10-09T10:06:37.888323Z","end":"2026-10-09T10:06:38.116528Z","steps":["trace[1438881513] 'process raft request' (duration: 227.217609ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:40.847826Z","caller":"traceutil/trace.go:171","msg":"trace[996879124] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"256.585596ms","start":"2026-10-09T10:06:40.591216Z","end":"2026-10-09T10:06:40.847802Z","steps":["trace[996879124] 'process raft request' (duration: 256.436813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:41.13378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.316756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-09T10:06:41.134037Z","caller":"traceutil/trace.go:171","msg":"trace[1085127131] linearizableReadLoop","detail":"{readStateIndex:27308; appliedIndex:27306; }","duration":"389.275212ms","start":"2026-10-09T10:06:40.744738Z","end":"2026-10-09T10:06:41.134014Z","steps":["trace[1085127131] 'read index received' (duration: 102.910381ms)","trace[1085127131] 'applied index is now lower than readState.Index' (duration: 286.362501ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:41.134235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.471647ms","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:8"} {"level":"info","ts":"2026-10-09T10:06:41.134268Z","caller":"traceutil/trace.go:171","msg":"trace[585038358] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26450; }","duration":"389.563009ms","start":"2026-10-09T10:06:40.744694Z","end":"2026-10-09T10:06:41.134258Z","steps":["trace[585038358] 'agreement among raft nodes before linearized reading' (duration: 389.439786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:41.134299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:40.744676Z","time spent":"389.61386ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:41.145682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.505477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fb5fa5f1-9fed-4709-8db9-e08361368179\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-10-09T10:06:41.145741Z","caller":"traceutil/trace.go:171","msg":"trace[1316691002] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fb5fa5f1-9fed-4709-8db9-e08361368179; range_end:; response_count:1; response_revision:26455; }","duration":"240.606339ms","start":"2026-10-09T10:06:40.905119Z","end":"2026-10-09T10:06:41.145726Z","steps":["trace[1316691002] 'agreement among raft nodes before linearized reading' (duration: 240.421095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:45.558779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.66587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:45.558858Z","caller":"traceutil/trace.go:171","msg":"trace[700531921] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26506; }","duration":"138.767732ms","start":"2026-10-09T10:06:45.420069Z","end":"2026-10-09T10:06:45.558836Z","steps":["trace[700531921] 'count revisions from in-memory index tree' (duration: 138.614649ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:45.706943Z","caller":"traceutil/trace.go:171","msg":"trace[1787131295] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"116.046955ms","start":"2026-10-09T10:06:45.590863Z","end":"2026-10-09T10:06:45.70691Z","steps":["trace[1787131295] 'process raft request' (duration: 115.873401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:46.000261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.968917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:46.000504Z","caller":"traceutil/trace.go:171","msg":"trace[1352665453] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"290.016573ms","start":"2026-10-09T10:06:45.71047Z","end":"2026-10-09T10:06:46.000486Z","steps":["trace[1352665453] 'process raft request' (duration: 56.755709ms)","trace[1352665453] 'compare' (duration: 232.853805ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:46.215886Z","caller":"traceutil/trace.go:171","msg":"trace[999254915] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"210.894984ms","start":"2026-10-09T10:06:46.004951Z","end":"2026-10-09T10:06:46.215846Z","steps":["trace[999254915] 'process raft request' (duration: 131.702115ms)","trace[999254915] 'compare' (duration: 78.974995ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:58.34007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.155423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:58.340152Z","caller":"traceutil/trace.go:171","msg":"trace[1532834588] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26603; }","duration":"123.292876ms","start":"2026-10-09T10:06:58.216838Z","end":"2026-10-09T10:06:58.340131Z","steps":["trace[1532834588] 'count revisions from in-memory index tree' (duration: 123.056121ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:58.498721Z","caller":"traceutil/trace.go:171","msg":"trace[1288343691] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"153.726507ms","start":"2026-10-09T10:06:58.344963Z","end":"2026-10-09T10:06:58.498689Z","steps":["trace[1288343691] 'process raft request' (duration: 153.385369ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:58.498754Z","caller":"traceutil/trace.go:171","msg":"trace[1102694995] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"153.115574ms","start":"2026-10-09T10:06:58.345608Z","end":"2026-10-09T10:06:58.498724Z","steps":["trace[1102694995] 'process raft request' (duration: 152.893979ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:58.498809Z","caller":"traceutil/trace.go:171","msg":"trace[562857112] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"153.765787ms","start":"2026-10-09T10:06:58.344997Z","end":"2026-10-09T10:06:58.498763Z","steps":["trace[562857112] 'process raft request' (duration: 153.470391ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:59.379958Z","caller":"traceutil/trace.go:171","msg":"trace[1998494283] linearizableReadLoop","detail":"{readStateIndex:27471; appliedIndex:27470; }","duration":"129.406763ms","start":"2026-10-09T10:06:59.250527Z","end":"2026-10-09T10:06:59.379934Z","steps":["trace[1998494283] 'read index received' (duration: 129.323251ms)","trace[1998494283] 'applied index is now lower than readState.Index' (duration: 82.132µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:59.380062Z","caller":"traceutil/trace.go:171","msg":"trace[939777458] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"135.933719ms","start":"2026-10-09T10:06:59.244095Z","end":"2026-10-09T10:06:59.380029Z","steps":["trace[939777458] 'process raft request' (duration: 135.689374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:59.380101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.538945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:59.38014Z","caller":"traceutil/trace.go:171","msg":"trace[148738853] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26609; }","duration":"129.637378ms","start":"2026-10-09T10:06:59.25049Z","end":"2026-10-09T10:06:59.380127Z","steps":["trace[148738853] 'agreement among raft nodes before linearized reading' (duration: 129.536456ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:59.435946Z","caller":"traceutil/trace.go:171","msg":"trace[1880313486] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"185.091088ms","start":"2026-10-09T10:06:59.250826Z","end":"2026-10-09T10:06:59.435917Z","steps":["trace[1880313486] 'process raft request' (duration: 184.780741ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:59.436051Z","caller":"traceutil/trace.go:171","msg":"trace[204566659] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"157.984892ms","start":"2026-10-09T10:06:59.278038Z","end":"2026-10-09T10:06:59.436023Z","steps":["trace[204566659] 'process raft request' (duration: 157.851319ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:59.436125Z","caller":"traceutil/trace.go:171","msg":"trace[955847381] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"140.44914ms","start":"2026-10-09T10:06:59.295665Z","end":"2026-10-09T10:06:59.436114Z","steps":["trace[955847381] 'process raft request' (duration: 140.274876ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:59.436212Z","caller":"traceutil/trace.go:171","msg":"trace[73635401] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"139.285013ms","start":"2026-10-09T10:06:59.296919Z","end":"2026-10-09T10:06:59.436204Z","steps":["trace[73635401] 'process raft request' (duration: 139.073379ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:59.436284Z","caller":"traceutil/trace.go:171","msg":"trace[1435985681] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"184.914015ms","start":"2026-10-09T10:06:59.251359Z","end":"2026-10-09T10:06:59.436273Z","steps":["trace[1435985681] 'process raft request' (duration: 184.456144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:59.783005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.274277ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:59.783134Z","caller":"traceutil/trace.go:171","msg":"trace[204230150] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26614; }","duration":"187.43069ms","start":"2026-10-09T10:06:59.595683Z","end":"2026-10-09T10:06:59.783114Z","steps":["trace[204230150] 'range keys from in-memory index tree' (duration: 187.230976ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:00.319939Z","caller":"traceutil/trace.go:171","msg":"trace[98056288] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"176.479636ms","start":"2026-10-09T10:07:00.143431Z","end":"2026-10-09T10:07:00.31991Z","steps":["trace[98056288] 'process raft request' (duration: 115.561524ms)","trace[98056288] 'compare' (duration: 60.81216ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:09.890866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.838456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:07:09.890996Z","caller":"traceutil/trace.go:171","msg":"trace[385827640] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"405.442404ms","start":"2026-10-09T10:07:09.48554Z","end":"2026-10-09T10:07:09.890983Z","steps":["trace[385827640] 'process raft request' (duration: 405.390312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:09.891032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:09.48553Z","time spent":"405.483365ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:09.891095Z","caller":"traceutil/trace.go:171","msg":"trace[1767102032] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"406.914167ms","start":"2026-10-09T10:07:09.484175Z","end":"2026-10-09T10:07:09.891089Z","steps":["trace[1767102032] 'process raft request' (duration: 194.782705ms)","trace[1767102032] 'compare' (duration: 211.744213ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:09.891114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:09.484138Z","time spent":"406.964689ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:09.891234Z","caller":"traceutil/trace.go:171","msg":"trace[1217997848] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"405.514886ms","start":"2026-10-09T10:07:09.485699Z","end":"2026-10-09T10:07:09.891214Z","steps":["trace[1217997848] 'process raft request' (duration: 405.257659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:09.891261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:09.485672Z","time spent":"405.571407ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:09.891411Z","caller":"traceutil/trace.go:171","msg":"trace[2140674060] linearizableReadLoop","detail":"{readStateIndex:27563; appliedIndex:27559; }","duration":"342.031356ms","start":"2026-10-09T10:07:09.549374Z","end":"2026-10-09T10:07:09.891397Z","steps":["trace[2140674060] 'read index received' (duration: 129.642898ms)","trace[2140674060] 'applied index is now lower than readState.Index' (duration: 212.379628ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:09.891475Z","caller":"traceutil/trace.go:171","msg":"trace[21703918] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"404.424202ms","start":"2026-10-09T10:07:09.487018Z","end":"2026-10-09T10:07:09.891442Z","steps":["trace[21703918] 'process raft request' (duration: 404.146386ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:09.891539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.140309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:09.891645Z","caller":"traceutil/trace.go:171","msg":"trace[345642561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26699; }","duration":"342.282482ms","start":"2026-10-09T10:07:09.549323Z","end":"2026-10-09T10:07:09.891605Z","steps":["trace[345642561] 'agreement among raft nodes before linearized reading' (duration: 342.128488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:09.891665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:09.487005Z","time spent":"404.549185ms","remote":"127.0.0.1:59440","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-10-09T10:07:09.89169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:09.549302Z","time spent":"342.379594ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T10:07:10.327754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.947351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T10:07:10.327815Z","caller":"traceutil/trace.go:171","msg":"trace[1755997107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26700; }","duration":"120.018102ms","start":"2026-10-09T10:07:10.207781Z","end":"2026-10-09T10:07:10.327799Z","steps":["trace[1755997107] 'range keys from in-memory index tree' (duration: 119.814038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:10.327846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.757033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:07:10.327947Z","caller":"traceutil/trace.go:171","msg":"trace[1608770090] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26700; }","duration":"245.925097ms","start":"2026-10-09T10:07:10.081998Z","end":"2026-10-09T10:07:10.327923Z","steps":["trace[1608770090] 'count revisions from in-memory index tree' (duration: 245.664762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:10.958643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360949878086936396,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T10:07:10.960709Z","caller":"traceutil/trace.go:171","msg":"trace[1922704326] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"625.193526ms","start":"2026-10-09T10:07:10.335493Z","end":"2026-10-09T10:07:10.960687Z","steps":["trace[1922704326] 'process raft request' (duration: 625.028482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:10.960868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.335455Z","time spent":"625.306898ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:11.257478Z","caller":"traceutil/trace.go:171","msg":"trace[636606963] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"837.877901ms","start":"2026-10-09T10:07:10.419565Z","end":"2026-10-09T10:07:11.257443Z","steps":["trace[636606963] 'process raft request' (duration: 786.291208ms)","trace[636606963] 'compare' (duration: 51.049671ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:11.25767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.419526Z","time spent":"838.020484ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:07:11.482739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.44858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-09T10:07:11.483147Z","caller":"traceutil/trace.go:171","msg":"trace[1364092747] transaction","detail":"{read_only:false; response_revision:26704; number_of_response:1; }","duration":"800.764282ms","start":"2026-10-09T10:07:10.682359Z","end":"2026-10-09T10:07:11.483123Z","steps":["trace[1364092747] 'process raft request' (duration: 800.500246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.483332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.682334Z","time spent":"800.879385ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:11.483342Z","caller":"traceutil/trace.go:171","msg":"trace[1195602688] linearizableReadLoop","detail":"{readStateIndex:27570; appliedIndex:27566; }","duration":"1.025289401s","start":"2026-10-09T10:07:10.458031Z","end":"2026-10-09T10:07:11.48332Z","steps":["trace[1195602688] 'read index received' (duration: 502.635846ms)","trace[1195602688] 'applied index is now lower than readState.Index' (duration: 522.651475ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:11.483411Z","caller":"traceutil/trace.go:171","msg":"trace[866963590] transaction","detail":"{read_only:false; response_revision:26705; number_of_response:1; }","duration":"600.235228ms","start":"2026-10-09T10:07:10.88314Z","end":"2026-10-09T10:07:11.483375Z","steps":["trace[866963590] 'process raft request' (duration: 599.916931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.483526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025484675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T10:07:11.483557Z","caller":"traceutil/trace.go:171","msg":"trace[278893971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26705; }","duration":"1.025544006s","start":"2026-10-09T10:07:10.458001Z","end":"2026-10-09T10:07:11.483545Z","steps":["trace[278893971] 'agreement among raft nodes before linearized reading' (duration: 1.025407193s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.483585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.457986Z","time spent":"1.025591628s","remote":"127.0.0.1:59440","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-10-09T10:07:11.483597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.883113Z","time spent":"600.378582ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:07:11.536067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056848607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:11.536195Z","caller":"traceutil/trace.go:171","msg":"trace[215091917] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26706; }","duration":"1.057023351s","start":"2026-10-09T10:07:10.479143Z","end":"2026-10-09T10:07:11.536166Z","steps":["trace[215091917] 'agreement among raft nodes before linearized reading' (duration: 1.056798816s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.536273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.479128Z","time spent":"1.057128423s","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-10-09T10:07:11.536732Z","caller":"traceutil/trace.go:171","msg":"trace[1582355517] transaction","detail":"{read_only:false; response_revision:26706; number_of_response:1; }","duration":"116.968865ms","start":"2026-10-09T10:07:11.419747Z","end":"2026-10-09T10:07:11.536716Z","steps":["trace[1582355517] 'process raft request' (duration: 115.989104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.537093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.122535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:11.537142Z","caller":"traceutil/trace.go:171","msg":"trace[1488231331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26706; }","duration":"642.215176ms","start":"2026-10-09T10:07:10.894914Z","end":"2026-10-09T10:07:11.537129Z","steps":["trace[1488231331] 'agreement among raft nodes before linearized reading' (duration: 642.141315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.53719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.894892Z","time spent":"642.287279ms","remote":"127.0.0.1:59116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:07:11.537486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.301971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-09T10:07:11.537512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.643701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T10:07:11.537538Z","caller":"traceutil/trace.go:171","msg":"trace[1616073277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26706; }","duration":"953.378363ms","start":"2026-10-09T10:07:10.584145Z","end":"2026-10-09T10:07:11.537523Z","steps":["trace[1616073277] 'agreement among raft nodes before linearized reading' (duration: 953.22281ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:11.537561Z","caller":"traceutil/trace.go:171","msg":"trace[1146897950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26706; }","duration":"967.717903ms","start":"2026-10-09T10:07:10.569831Z","end":"2026-10-09T10:07:11.537549Z","steps":["trace[1146897950] 'agreement among raft nodes before linearized reading' (duration: 967.545979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.537573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.584128Z","time spent":"953.435924ms","remote":"127.0.0.1:59440","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-10-09T10:07:11.537599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.569824Z","time spent":"967.762185ms","remote":"127.0.0.1:59440","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":"warn","ts":"2026-10-09T10:07:11.537709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.867516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:07:11.537727Z","caller":"traceutil/trace.go:171","msg":"trace[733741779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26706; }","duration":"967.941299ms","start":"2026-10-09T10:07:10.569782Z","end":"2026-10-09T10:07:11.537723Z","steps":["trace[733741779] 'agreement among raft nodes before linearized reading' (duration: 967.897568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.537739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:10.569746Z","time spent":"967.989329ms","remote":"127.0.0.1:59440","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":"info","ts":"2026-10-09T10:07:11.699537Z","caller":"traceutil/trace.go:171","msg":"trace[1966575276] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"156.901837ms","start":"2026-10-09T10:07:11.542584Z","end":"2026-10-09T10:07:11.699486Z","steps":["trace[1966575276] 'process raft request' (duration: 91.169418ms)","trace[1966575276] 'compare' (duration: 65.249818ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:11.699917Z","caller":"traceutil/trace.go:171","msg":"trace[217673163] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"156.383707ms","start":"2026-10-09T10:07:11.54349Z","end":"2026-10-09T10:07:11.699873Z","steps":["trace[217673163] 'process raft request' (duration: 155.789984ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:11.69995Z","caller":"traceutil/trace.go:171","msg":"trace[1691444977] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"154.264249ms","start":"2026-10-09T10:07:11.54567Z","end":"2026-10-09T10:07:11.699934Z","steps":["trace[1691444977] 'process raft request' (duration: 154.113356ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:11.699971Z","caller":"traceutil/trace.go:171","msg":"trace[216411822] transaction","detail":"{read_only:false; response_revision:26711; number_of_response:1; }","duration":"146.483544ms","start":"2026-10-09T10:07:11.553469Z","end":"2026-10-09T10:07:11.699952Z","steps":["trace[216411822] 'process raft request' (duration: 146.367132ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:11.699919Z","caller":"traceutil/trace.go:171","msg":"trace[1936616021] linearizableReadLoop","detail":"{readStateIndex:27576; appliedIndex:27572; }","duration":"140.47112ms","start":"2026-10-09T10:07:11.559423Z","end":"2026-10-09T10:07:11.699894Z","steps":["trace[1936616021] 'read index received' (duration: 74.416634ms)","trace[1936616021] 'applied index is now lower than readState.Index' (duration: 66.053106ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:11.700029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.245574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:11.700072Z","caller":"traceutil/trace.go:171","msg":"trace[1911233447] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26711; }","duration":"156.306345ms","start":"2026-10-09T10:07:11.543755Z","end":"2026-10-09T10:07:11.700061Z","steps":["trace[1911233447] 'agreement among raft nodes before linearized reading' (duration: 156.217763ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:23.951031Z","caller":"traceutil/trace.go:171","msg":"trace[291767428] linearizableReadLoop","detail":"{readStateIndex:27663; appliedIndex:27661; }","duration":"163.580947ms","start":"2026-10-09T10:07:23.787425Z","end":"2026-10-09T10:07:23.951006Z","steps":["trace[291767428] 'read index received' (duration: 123.00901ms)","trace[291767428] 'applied index is now lower than readState.Index' (duration: 40.570917ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:23.951368Z","caller":"traceutil/trace.go:171","msg":"trace[1602060352] transaction","detail":"{read_only:false; response_revision:26796; number_of_response:1; }","duration":"184.973875ms","start":"2026-10-09T10:07:23.766374Z","end":"2026-10-09T10:07:23.951348Z","steps":["trace[1602060352] 'process raft request' (duration: 184.565505ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:23.951376Z","caller":"traceutil/trace.go:171","msg":"trace[1657451041] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"263.401438ms","start":"2026-10-09T10:07:23.687939Z","end":"2026-10-09T10:07:23.95134Z","steps":["trace[1657451041] 'process raft request' (duration: 222.400541ms)","trace[1657451041] 'compare' (duration: 40.341162ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:23.951594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.14756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:07:23.951697Z","caller":"traceutil/trace.go:171","msg":"trace[511140931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26796; }","duration":"164.303064ms","start":"2026-10-09T10:07:23.787373Z","end":"2026-10-09T10:07:23.951676Z","steps":["trace[511140931] 'agreement among raft nodes before linearized reading' (duration: 164.034498ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:24.045608Z","caller":"traceutil/trace.go:171","msg":"trace[2002548405] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"103.34283ms","start":"2026-10-09T10:07:23.942236Z","end":"2026-10-09T10:07:24.045579Z","steps":["trace[2002548405] 'process raft request' (duration: 103.199187ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:24.308804Z","caller":"traceutil/trace.go:171","msg":"trace[585072275] transaction","detail":"{read_only:false; response_revision:26799; number_of_response:1; }","duration":"359.330382ms","start":"2026-10-09T10:07:23.949445Z","end":"2026-10-09T10:07:24.308776Z","steps":["trace[585072275] 'process raft request' (duration: 359.22829ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:24.308857Z","caller":"traceutil/trace.go:171","msg":"trace[1197917706] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"359.504876ms","start":"2026-10-09T10:07:23.949317Z","end":"2026-10-09T10:07:24.308822Z","steps":["trace[1197917706] 'process raft request' (duration: 359.150138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:24.30898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.949417Z","time spent":"359.469696ms","remote":"127.0.0.1:59440","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-10-09T10:07:24.309044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.949302Z","time spent":"359.62888ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:24.309192Z","caller":"traceutil/trace.go:171","msg":"trace[710872112] linearizableReadLoop","detail":"{readStateIndex:27667; appliedIndex:27663; }","duration":"358.064084ms","start":"2026-10-09T10:07:23.951104Z","end":"2026-10-09T10:07:24.309168Z","steps":["trace[710872112] 'read index received' (duration: 94.39218ms)","trace[710872112] 'applied index is now lower than readState.Index' (duration: 263.670864ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:24.309233Z","caller":"traceutil/trace.go:171","msg":"trace[202093132] transaction","detail":"{read_only:false; response_revision:26800; number_of_response:1; }","duration":"359.312552ms","start":"2026-10-09T10:07:23.949902Z","end":"2026-10-09T10:07:24.309215Z","steps":["trace[202093132] 'process raft request' (duration: 358.831641ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:24.309293Z","caller":"traceutil/trace.go:171","msg":"trace[339418232] transaction","detail":"{read_only:false; response_revision:26802; number_of_response:1; }","duration":"352.94191ms","start":"2026-10-09T10:07:23.956326Z","end":"2026-10-09T10:07:24.309268Z","steps":["trace[339418232] 'process raft request' (duration: 352.801787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:24.309314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.949877Z","time spent":"359.389984ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:24.309315Z","caller":"traceutil/trace.go:171","msg":"trace[248176436] transaction","detail":"{read_only:false; response_revision:26801; number_of_response:1; }","duration":"357.299457ms","start":"2026-10-09T10:07:23.951936Z","end":"2026-10-09T10:07:24.309236Z","steps":["trace[248176436] 'process raft request' (duration: 357.104603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:24.309447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.957663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-09T10:07:24.309448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.956305Z","time spent":"353.053352ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:07:24.309467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.95192Z","time spent":"357.466471ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:24.309488Z","caller":"traceutil/trace.go:171","msg":"trace[250526380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26802; }","duration":"520.027285ms","start":"2026-10-09T10:07:23.789445Z","end":"2026-10-09T10:07:24.309472Z","steps":["trace[250526380] 'agreement among raft nodes before linearized reading' (duration: 519.854721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:24.309521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.789437Z","time spent":"520.075276ms","remote":"127.0.0.1:59440","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-10-09T10:07:24.309518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.437919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T10:07:24.309536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.484278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T10:07:24.309577Z","caller":"traceutil/trace.go:171","msg":"trace[209325905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26802; }","duration":"521.544009ms","start":"2026-10-09T10:07:23.788022Z","end":"2026-10-09T10:07:24.309566Z","steps":["trace[209325905] 'agreement among raft nodes before linearized reading' (duration: 521.424186ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:24.309577Z","caller":"traceutil/trace.go:171","msg":"trace[383545625] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:26802; }","duration":"280.535602ms","start":"2026-10-09T10:07:24.029025Z","end":"2026-10-09T10:07:24.309561Z","steps":["trace[383545625] 'agreement among raft nodes before linearized reading' (duration: 280.434189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:24.309611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:23.788015Z","time spent":"521.587981ms","remote":"127.0.0.1:59440","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-10-09T10:07:24.479155Z","caller":"traceutil/trace.go:171","msg":"trace[1517756779] transaction","detail":"{read_only:false; response_revision:26803; number_of_response:1; }","duration":"165.293445ms","start":"2026-10-09T10:07:24.313837Z","end":"2026-10-09T10:07:24.479131Z","steps":["trace[1517756779] 'process raft request' (duration: 80.428998ms)","trace[1517756779] 'compare' (duration: 84.392056ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:24.479225Z","caller":"traceutil/trace.go:171","msg":"trace[1454534591] transaction","detail":"{read_only:false; response_revision:26804; number_of_response:1; }","duration":"164.874666ms","start":"2026-10-09T10:07:24.31432Z","end":"2026-10-09T10:07:24.479194Z","steps":["trace[1454534591] 'process raft request' (duration: 164.489518ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:29.488395Z","caller":"traceutil/trace.go:171","msg":"trace[996075161] transaction","detail":"{read_only:false; response_revision:26835; number_of_response:1; }","duration":"141.39534ms","start":"2026-10-09T10:07:29.346975Z","end":"2026-10-09T10:07:29.48837Z","steps":["trace[996075161] 'process raft request' (duration: 141.217326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:35.168019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.563357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T10:07:35.168117Z","caller":"traceutil/trace.go:171","msg":"trace[405069805] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26877; }","duration":"227.702811ms","start":"2026-10-09T10:07:34.940385Z","end":"2026-10-09T10:07:35.168088Z","steps":["trace[405069805] 'count revisions from in-memory index tree' (duration: 227.449675ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:35.60971Z","caller":"traceutil/trace.go:171","msg":"trace[150344484] transaction","detail":"{read_only:false; response_revision:26878; number_of_response:1; }","duration":"144.706994ms","start":"2026-10-09T10:07:35.464975Z","end":"2026-10-09T10:07:35.609682Z","steps":["trace[150344484] 'process raft request' (duration: 144.53383ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:35.623219Z","caller":"traceutil/trace.go:171","msg":"trace[21264166] transaction","detail":"{read_only:false; response_revision:26879; number_of_response:1; }","duration":"118.025258ms","start":"2026-10-09T10:07:35.505169Z","end":"2026-10-09T10:07:35.623194Z","steps":["trace[21264166] 'process raft request' (duration: 117.762863ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:36.184086Z","caller":"traceutil/trace.go:171","msg":"trace[1914140182] transaction","detail":"{read_only:false; response_revision:26884; number_of_response:1; }","duration":"133.248128ms","start":"2026-10-09T10:07:36.050788Z","end":"2026-10-09T10:07:36.184036Z","steps":["trace[1914140182] 'process raft request' (duration: 133.098835ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:36.216453Z","caller":"traceutil/trace.go:171","msg":"trace[162719495] linearizableReadLoop","detail":"{readStateIndex:27755; appliedIndex:27753; }","duration":"114.903378ms","start":"2026-10-09T10:07:36.10152Z","end":"2026-10-09T10:07:36.216423Z","steps":["trace[162719495] 'read index received' (duration: 82.482333ms)","trace[162719495] 'applied index is now lower than readState.Index' (duration: 32.420015ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:36.216492Z","caller":"traceutil/trace.go:171","msg":"trace[1482165455] transaction","detail":"{read_only:false; response_revision:26885; number_of_response:1; }","duration":"131.823956ms","start":"2026-10-09T10:07:36.084643Z","end":"2026-10-09T10:07:36.216467Z","steps":["trace[1482165455] 'process raft request' (duration: 131.640102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.216604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.041221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:36.216673Z","caller":"traceutil/trace.go:171","msg":"trace[1002612818] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26885; }","duration":"115.171085ms","start":"2026-10-09T10:07:36.101488Z","end":"2026-10-09T10:07:36.216659Z","steps":["trace[1002612818] 'agreement among raft nodes before linearized reading' (duration: 115.036212ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:36.609655Z","caller":"traceutil/trace.go:171","msg":"trace[306838797] linearizableReadLoop","detail":"{readStateIndex:27759; appliedIndex:27756; }","duration":"183.970672ms","start":"2026-10-09T10:07:36.425637Z","end":"2026-10-09T10:07:36.609608Z","steps":["trace[306838797] 'read index received' (duration: 19.881734ms)","trace[306838797] 'applied index is now lower than readState.Index' (duration: 164.088058ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:36.609751Z","caller":"traceutil/trace.go:171","msg":"trace[38202550] transaction","detail":"{read_only:false; response_revision:26889; number_of_response:1; }","duration":"230.336799ms","start":"2026-10-09T10:07:36.379404Z","end":"2026-10-09T10:07:36.609741Z","steps":["trace[38202550] 'process raft request' (duration: 230.118224ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:36.609765Z","caller":"traceutil/trace.go:171","msg":"trace[413270182] transaction","detail":"{read_only:false; response_revision:26888; number_of_response:1; }","duration":"230.511763ms","start":"2026-10-09T10:07:36.379231Z","end":"2026-10-09T10:07:36.609743Z","steps":["trace[413270182] 'process raft request' (duration: 163.533055ms)","trace[413270182] 'compare' (duration: 66.284443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:36.609968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.316149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:07:36.610038Z","caller":"traceutil/trace.go:171","msg":"trace[941643773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26889; }","duration":"184.442682ms","start":"2026-10-09T10:07:36.425582Z","end":"2026-10-09T10:07:36.610025Z","steps":["trace[941643773] 'agreement among raft nodes before linearized reading' (duration: 184.279438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.974514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.306387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:07:36.9747Z","caller":"traceutil/trace.go:171","msg":"trace[2045568358] transaction","detail":"{read_only:false; response_revision:26891; number_of_response:1; }","duration":"360.214703ms","start":"2026-10-09T10:07:36.614467Z","end":"2026-10-09T10:07:36.974682Z","steps":["trace[2045568358] 'process raft request' (duration: 360.1416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.974772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.614458Z","time spent":"360.280494ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:07:36.974944Z","caller":"traceutil/trace.go:171","msg":"trace[1792190397] transaction","detail":"{read_only:false; response_revision:26890; number_of_response:1; }","duration":"373.181253ms","start":"2026-10-09T10:07:36.601751Z","end":"2026-10-09T10:07:36.974932Z","steps":["trace[1792190397] 'process raft request' (duration: 117.375974ms)","trace[1792190397] 'compare' (duration: 255.210825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:36.974995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.601734Z","time spent":"373.232704ms","remote":"127.0.0.1:59440","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-10-09T10:07:36.975204Z","caller":"traceutil/trace.go:171","msg":"trace[53901216] linearizableReadLoop","detail":"{readStateIndex:27760; appliedIndex:27759; }","duration":"365.474901ms","start":"2026-10-09T10:07:36.609714Z","end":"2026-10-09T10:07:36.975189Z","steps":["trace[53901216] 'read index received' (duration: 109.399886ms)","trace[53901216] 'applied index is now lower than readState.Index' (duration: 256.073675ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:36.975308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.696445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T10:07:36.97533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.7321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:36.975362Z","caller":"traceutil/trace.go:171","msg":"trace[1845380090] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26891; }","duration":"256.790181ms","start":"2026-10-09T10:07:36.718565Z","end":"2026-10-09T10:07:36.975356Z","steps":["trace[1845380090] 'agreement among raft nodes before linearized reading' (duration: 256.704009ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:36.975366Z","caller":"traceutil/trace.go:171","msg":"trace[1235310966] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26891; }","duration":"369.830887ms","start":"2026-10-09T10:07:36.605519Z","end":"2026-10-09T10:07:36.97535Z","steps":["trace[1235310966] 'agreement among raft nodes before linearized reading' (duration: 369.688944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.975394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.605495Z","time spent":"369.893409ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":10,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:07:36.975373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.39601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:07:36.975436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.01656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T10:07:36.975463Z","caller":"traceutil/trace.go:171","msg":"trace[1383436385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26891; }","duration":"497.519082ms","start":"2026-10-09T10:07:36.477919Z","end":"2026-10-09T10:07:36.975438Z","steps":["trace[1383436385] 'agreement among raft nodes before linearized reading' (duration: 497.326448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.975442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.895347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T10:07:36.975482Z","caller":"traceutil/trace.go:171","msg":"trace[650384367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26891; }","duration":"284.088371ms","start":"2026-10-09T10:07:36.69138Z","end":"2026-10-09T10:07:36.975469Z","steps":["trace[650384367] 'agreement among raft nodes before linearized reading' (duration: 283.891117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.975514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.477904Z","time spent":"497.598034ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T10:07:36.975482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.868768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T10:07:36.975528Z","caller":"traceutil/trace.go:171","msg":"trace[600537883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26891; }","duration":"386.012889ms","start":"2026-10-09T10:07:36.589494Z","end":"2026-10-09T10:07:36.975507Z","steps":["trace[600537883] 'agreement among raft nodes before linearized reading' (duration: 385.710022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.975569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.589484Z","time spent":"386.076521ms","remote":"127.0.0.1:59440","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-10-09T10:07:36.975582Z","caller":"traceutil/trace.go:171","msg":"trace[425529599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26891; }","duration":"421.012961ms","start":"2026-10-09T10:07:36.554543Z","end":"2026-10-09T10:07:36.975556Z","steps":["trace[425529599] 'agreement among raft nodes before linearized reading' (duration: 420.657743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.975674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.554527Z","time spent":"421.133604ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-09T10:07:37.293609Z","caller":"traceutil/trace.go:171","msg":"trace[1647566392] transaction","detail":"{read_only:false; response_revision:26894; number_of_response:1; }","duration":"312.646008ms","start":"2026-10-09T10:07:36.980937Z","end":"2026-10-09T10:07:37.293583Z","steps":["trace[1647566392] 'process raft request' (duration: 312.512035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:37.293764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:07:36.980912Z","time spent":"312.782212ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:00.577729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.248878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:08:00.577824Z","caller":"traceutil/trace.go:171","msg":"trace[661580623] linearizableReadLoop","detail":"{readStateIndex:28088; appliedIndex:28087; }","duration":"179.798899ms","start":"2026-10-09T10:08:00.39801Z","end":"2026-10-09T10:08:00.577809Z","steps":["trace[661580623] 'read index received' (duration: 45.271µs)","trace[661580623] 'applied index is now lower than readState.Index' (duration: 179.752238ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:00.577852Z","caller":"traceutil/trace.go:171","msg":"trace[1023880582] transaction","detail":"{read_only:false; response_revision:27212; number_of_response:1; }","duration":"262.844886ms","start":"2026-10-09T10:08:00.314976Z","end":"2026-10-09T10:08:00.577821Z","steps":["trace[1023880582] 'process raft request' (duration: 15.017877ms)","trace[1023880582] 'compare' (duration: 247.141014ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:08:00.57791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.904332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:00.577939Z","caller":"traceutil/trace.go:171","msg":"trace[1306221400] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:27212; }","duration":"179.972223ms","start":"2026-10-09T10:08:00.397956Z","end":"2026-10-09T10:08:00.577928Z","steps":["trace[1306221400] 'agreement among raft nodes before linearized reading' (duration: 179.916101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.016284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.37293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:08:01.017056Z","caller":"traceutil/trace.go:171","msg":"trace[1431206523] transaction","detail":"{read_only:false; response_revision:27213; number_of_response:1; }","duration":"552.623884ms","start":"2026-10-09T10:08:00.464383Z","end":"2026-10-09T10:08:01.017007Z","steps":["trace[1431206523] 'process raft request' (duration: 342.424965ms)","trace[1431206523] 'compare' (duration: 209.191736ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:01.017129Z","caller":"traceutil/trace.go:171","msg":"trace[1390619898] transaction","detail":"{read_only:false; response_revision:27214; number_of_response:1; }","duration":"482.153909ms","start":"2026-10-09T10:08:00.534955Z","end":"2026-10-09T10:08:01.017109Z","steps":["trace[1390619898] 'process raft request' (duration: 481.936104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.017418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.534932Z","time spent":"482.400224ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:01.017267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.46435Z","time spent":"552.802718ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:08:01.017485Z","caller":"traceutil/trace.go:171","msg":"trace[652777603] transaction","detail":"{read_only:false; response_revision:27216; number_of_response:1; }","duration":"312.917936ms","start":"2026-10-09T10:08:00.704533Z","end":"2026-10-09T10:08:01.017451Z","steps":["trace[652777603] 'process raft request' (duration: 312.741612ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.017396Z","caller":"traceutil/trace.go:171","msg":"trace[1767059902] transaction","detail":"{read_only:false; response_revision:27215; number_of_response:1; }","duration":"314.760966ms","start":"2026-10-09T10:08:00.702593Z","end":"2026-10-09T10:08:01.017354Z","steps":["trace[1767059902] 'process raft request' (duration: 314.46576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.017667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.704506Z","time spent":"313.050179ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:01.017687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.702567Z","time spent":"315.012171ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:01.177351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.941896ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-09T10:08:01.177984Z","caller":"traceutil/trace.go:171","msg":"trace[956903647] linearizableReadLoop","detail":"{readStateIndex:28090; appliedIndex:28088; }","duration":"600.110625ms","start":"2026-10-09T10:08:00.577846Z","end":"2026-10-09T10:08:01.177956Z","steps":["trace[956903647] 'read index received' (duration: 228.972629ms)","trace[956903647] 'applied index is now lower than readState.Index' (duration: 371.135136ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:01.178145Z","caller":"traceutil/trace.go:171","msg":"trace[234423401] transaction","detail":"{read_only:false; response_revision:27217; number_of_response:1; }","duration":"130.908596ms","start":"2026-10-09T10:08:01.047226Z","end":"2026-10-09T10:08:01.178135Z","steps":["trace[234423401] 'process raft request' (duration: 130.235442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.448851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.648629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.448956Z","caller":"traceutil/trace.go:171","msg":"trace[1541893746] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:27217; }","duration":"902.798032ms","start":"2026-10-09T10:08:00.546128Z","end":"2026-10-09T10:08:01.448926Z","steps":["trace[1541893746] 'agreement among raft nodes before linearized reading' (duration: 631.945067ms)","trace[1541893746] 'count revisions from in-memory index tree' (duration: 270.712852ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:08:01.449034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.546104Z","time spent":"902.892114ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:08:01.449026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.084146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.449119Z","caller":"traceutil/trace.go:171","msg":"trace[14192726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27217; }","duration":"970.217989ms","start":"2026-10-09T10:08:00.478874Z","end":"2026-10-09T10:08:01.449092Z","steps":["trace[14192726] 'agreement among raft nodes before linearized reading' (duration: 699.21662ms)","trace[14192726] 'range keys from in-memory index tree' (duration: 270.880446ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:08:01.44917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.478857Z","time spent":"970.300901ms","remote":"127.0.0.1:59124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T10:08:01.44953Z","caller":"traceutil/trace.go:171","msg":"trace[1952066288] linearizableReadLoop","detail":"{readStateIndex:28095; appliedIndex:28094; }","duration":"271.458689ms","start":"2026-10-09T10:08:01.178042Z","end":"2026-10-09T10:08:01.449501Z","steps":["trace[1952066288] 'read index received' (duration: 215.805075ms)","trace[1952066288] 'applied index is now lower than readState.Index' (duration: 55.652344ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:01.449669Z","caller":"traceutil/trace.go:171","msg":"trace[32149339] transaction","detail":"{read_only:false; response_revision:27218; number_of_response:1; }","duration":"343.490738ms","start":"2026-10-09T10:08:01.106119Z","end":"2026-10-09T10:08:01.449609Z","steps":["trace[32149339] 'process raft request' (duration: 287.701211ms)","trace[32149339] 'compare' (duration: 55.063751ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:08:01.449808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.515366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-09T10:08:01.449804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.492832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T10:08:01.449826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.802872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T10:08:01.44986Z","caller":"traceutil/trace.go:171","msg":"trace[1620996065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27218; }","duration":"236.598568ms","start":"2026-10-09T10:08:01.213243Z","end":"2026-10-09T10:08:01.449842Z","steps":["trace[1620996065] 'agreement among raft nodes before linearized reading' (duration: 236.431755ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.449866Z","caller":"traceutil/trace.go:171","msg":"trace[563012304] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:27218; }","duration":"111.603314ms","start":"2026-10-09T10:08:01.338247Z","end":"2026-10-09T10:08:01.44985Z","steps":["trace[563012304] 'agreement among raft nodes before linearized reading' (duration: 111.477771ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.449903Z","caller":"traceutil/trace.go:171","msg":"trace[1135830448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27218; }","duration":"350.944305ms","start":"2026-10-09T10:08:01.098939Z","end":"2026-10-09T10:08:01.449883Z","steps":["trace[1135830448] 'agreement among raft nodes before linearized reading' (duration: 350.71261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.449906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:01.106082Z","time spent":"343.734564ms","remote":"127.0.0.1:59346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19373,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:01.449948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:01.098921Z","time spent":"351.017397ms","remote":"127.0.0.1:59440","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-10-09T10:08:01.450019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.642218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T10:08:01.450085Z","caller":"traceutil/trace.go:171","msg":"trace[336974415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27218; }","duration":"231.708019ms","start":"2026-10-09T10:08:01.218358Z","end":"2026-10-09T10:08:01.450067Z","steps":["trace[336974415] 'agreement among raft nodes before linearized reading' (duration: 231.479814ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.694703Z","caller":"traceutil/trace.go:171","msg":"trace[1527469455] transaction","detail":"{read_only:false; response_revision:27220; number_of_response:1; }","duration":"237.412988ms","start":"2026-10-09T10:08:01.457257Z","end":"2026-10-09T10:08:01.69467Z","steps":["trace[1527469455] 'process raft request' (duration: 237.197854ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.694868Z","caller":"traceutil/trace.go:171","msg":"trace[1860351703] linearizableReadLoop","detail":"{readStateIndex:28098; appliedIndex:28095; }","duration":"225.799088ms","start":"2026-10-09T10:08:01.469046Z","end":"2026-10-09T10:08:01.694845Z","steps":["trace[1860351703] 'read index received' (duration: 70.852605ms)","trace[1860351703] 'applied index is now lower than readState.Index' (duration: 154.944543ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:01.694991Z","caller":"traceutil/trace.go:171","msg":"trace[968593293] transaction","detail":"{read_only:false; response_revision:27221; number_of_response:1; }","duration":"237.200833ms","start":"2026-10-09T10:08:01.457774Z","end":"2026-10-09T10:08:01.694975Z","steps":["trace[968593293] 'process raft request' (duration: 236.820205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.695086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.906697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.695161Z","caller":"traceutil/trace.go:171","msg":"trace[1501052697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27221; }","duration":"216.03549ms","start":"2026-10-09T10:08:01.479108Z","end":"2026-10-09T10:08:01.695143Z","steps":["trace[1501052697] 'agreement among raft nodes before linearized reading' (duration: 215.913447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.695195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.493894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:08:01.695199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.918571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.695253Z","caller":"traceutil/trace.go:171","msg":"trace[1387452752] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27221; }","duration":"137.635187ms","start":"2026-10-09T10:08:01.557603Z","end":"2026-10-09T10:08:01.695238Z","steps":["trace[1387452752] 'agreement among raft nodes before linearized reading' (duration: 137.529274ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.695265Z","caller":"traceutil/trace.go:171","msg":"trace[270341661] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27221; }","duration":"137.014613ms","start":"2026-10-09T10:08:01.558233Z","end":"2026-10-09T10:08:01.695248Z","steps":["trace[270341661] 'agreement among raft nodes before linearized reading' (duration: 136.88961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.695406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.590746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.695495Z","caller":"traceutil/trace.go:171","msg":"trace[566707677] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27221; }","duration":"137.727669ms","start":"2026-10-09T10:08:01.557746Z","end":"2026-10-09T10:08:01.695473Z","steps":["trace[566707677] 'agreement among raft nodes before linearized reading' (duration: 137.538195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.695567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.955154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.695645Z","caller":"traceutil/trace.go:171","msg":"trace[1832027296] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:27221; }","duration":"104.009505ms","start":"2026-10-09T10:08:01.591589Z","end":"2026-10-09T10:08:01.695598Z","steps":["trace[1832027296] 'agreement among raft nodes before linearized reading' (duration: 103.928573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.695762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.715458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T10:08:01.69582Z","caller":"traceutil/trace.go:171","msg":"trace[30347628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27221; }","duration":"226.8048ms","start":"2026-10-09T10:08:01.468996Z","end":"2026-10-09T10:08:01.6958Z","steps":["trace[30347628] 'agreement among raft nodes before linearized reading' (duration: 226.604516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.696672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.425116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.696733Z","caller":"traceutil/trace.go:171","msg":"trace[1149365721] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27221; }","duration":"139.524238ms","start":"2026-10-09T10:08:01.557192Z","end":"2026-10-09T10:08:01.696716Z","steps":["trace[1149365721] 'agreement among raft nodes before linearized reading' (duration: 139.396755ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:01.929681Z","caller":"traceutil/trace.go:171","msg":"trace[231284304] transaction","detail":"{read_only:false; response_revision:27222; number_of_response:1; }","duration":"229.305556ms","start":"2026-10-09T10:08:01.700344Z","end":"2026-10-09T10:08:01.92965Z","steps":["trace[231284304] 'process raft request' (duration: 159.552177ms)","trace[231284304] 'compare' (duration: 69.257558ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:01.929911Z","caller":"traceutil/trace.go:171","msg":"trace[977721398] linearizableReadLoop","detail":"{readStateIndex:28100; appliedIndex:28098; }","duration":"101.815057ms","start":"2026-10-09T10:08:01.82808Z","end":"2026-10-09T10:08:01.929895Z","steps":["trace[977721398] 'read index received' (duration: 31.821592ms)","trace[977721398] 'applied index is now lower than readState.Index' (duration: 69.992525ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:01.929978Z","caller":"traceutil/trace.go:171","msg":"trace[524533382] transaction","detail":"{read_only:false; response_revision:27223; number_of_response:1; }","duration":"174.02428ms","start":"2026-10-09T10:08:01.755924Z","end":"2026-10-09T10:08:01.929948Z","steps":["trace[524533382] 'process raft request' (duration: 173.619701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:01.930008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.917039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:01.93015Z","caller":"traceutil/trace.go:171","msg":"trace[2015678749] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27223; }","duration":"102.096333ms","start":"2026-10-09T10:08:01.828028Z","end":"2026-10-09T10:08:01.930125Z","steps":["trace[2015678749] 'agreement among raft nodes before linearized reading' (duration: 101.920719ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:09:22.003603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25083} {"level":"info","ts":"2026-10-09T10:09:22.153357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25083,"took":"146.602367ms","hash":663814226,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":37130240,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T10:09:22.153448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":663814226,"revision":25083,"compact-revision":21938} {"level":"info","ts":"2026-10-09T10:11:16.053013Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T10:11:16.064257Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T10:11:16.064352Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T10:14:22.011783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27916} {"level":"info","ts":"2026-10-09T10:14:22.162392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27916,"took":"146.27371ms","hash":3687273837,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":34152448,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T10:14:22.162472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3687273837,"revision":27916,"compact-revision":25083} {"level":"info","ts":"2026-10-09T10:19:22.018536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30542} {"level":"info","ts":"2026-10-09T10:19:22.168496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30542,"took":"145.82278ms","hash":1543347963,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":30863360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T10:19:22.168567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1543347963,"revision":30542,"compact-revision":27916} {"level":"info","ts":"2026-10-09T10:24:22.024559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32792} {"level":"info","ts":"2026-10-09T10:24:22.188803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32792,"took":"159.973156ms","hash":1734481806,"current-db-size-bytes":58753024,"current-db-size":"59 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T10:24:22.188872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1734481806,"revision":32792,"compact-revision":30542} {"level":"info","ts":"2026-10-09T10:29:22.03405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35022} {"level":"info","ts":"2026-10-09T10:29:22.191025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35022,"took":"152.686265ms","hash":3889588000,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":31318016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T10:29:22.191091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3889588000,"revision":35022,"compact-revision":32792} {"level":"info","ts":"2026-10-09T10:32:33.689661Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T10:32:33.69569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T10:32:33.695783Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T10:34:22.042938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37316} {"level":"info","ts":"2026-10-09T10:34:22.166285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37316,"took":"119.90218ms","hash":1660641558,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":32215040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T10:34:22.166343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1660641558,"revision":37316,"compact-revision":35022} {"level":"info","ts":"2026-10-09T10:39:22.049164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39593} {"level":"info","ts":"2026-10-09T10:39:22.186949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39593,"took":"133.573327ms","hash":2091571724,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":30044160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T10:39:22.187016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2091571724,"revision":39593,"compact-revision":37316} {"level":"info","ts":"2026-10-09T10:44:22.055539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41821} {"level":"info","ts":"2026-10-09T10:44:22.178341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41821,"took":"118.732337ms","hash":3656392864,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":29061120,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T10:44:22.178412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3656392864,"revision":41821,"compact-revision":39593} {"level":"info","ts":"2026-10-09T10:49:22.061503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44046} {"level":"info","ts":"2026-10-09T10:49:22.181579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44046,"took":"115.81873ms","hash":277580012,"current-db-size-bytes":59224064,"current-db-size":"59 MB","current-db-size-in-use-bytes":28856320,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T10:49:22.181668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":277580012,"revision":44046,"compact-revision":41821}