{"level":"warn","ts":"2026-10-13T12:53:20.950814Z","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-13T12:53:20.950959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.32: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.32:2380","--initial-cluster=instance=https://162.253.55.32:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.32:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.32: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-13T12:53:20.951057Z","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-13T12:53:20.951068Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-10-13T12:53:20.951096Z","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-13T12:53:20.952009Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"]} {"level":"info","ts":"2026-10-13T12:53:20.952114Z","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.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.32: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-13T12:53:20.95803Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.553172ms"} {"level":"info","ts":"2026-10-13T12:53:20.965218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c665390cd9eacc14","cluster-id":"d19b610fbe64baae"} {"level":"info","ts":"2026-10-13T12:53:20.965284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T12:53:20.965311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 0"} {"level":"info","ts":"2026-10-13T12:53:20.965323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c665390cd9eacc14 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T12:53:20.965333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 1"} {"level":"info","ts":"2026-10-13T12:53:20.965372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"warn","ts":"2026-10-13T12:53:20.976303Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T12:53:20.980277Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T12:53:20.981996Z","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-13T12:53:20.985119Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c665390cd9eacc14","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T12:53:20.985428Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c665390cd9eacc14","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T12:53:20.985633Z","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-13T12:53:20.985688Z","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-13T12:53:20.9857Z","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-13T12:53:20.98756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"info","ts":"2026-10-13T12:53:20.987773Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","added-peer-id":"c665390cd9eacc14","added-peer-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-10-13T12:53:20.988127Z","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-13T12:53:20.988389Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-10-13T12:53:20.988473Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-10-13T12:53:20.988471Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c665390cd9eacc14","initial-advertise-peer-urls":["https://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T12:53:20.988784Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T12:53:21.066752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T12:53:21.066833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T12:53:21.066872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgPreVoteResp from c665390cd9eacc14 at term 1"} {"level":"info","ts":"2026-10-13T12:53:21.066904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became candidate at term 2"} {"level":"info","ts":"2026-10-13T12:53:21.066919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgVoteResp from c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-10-13T12:53:21.066945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became leader at term 2"} {"level":"info","ts":"2026-10-13T12:53:21.066962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c665390cd9eacc14 elected leader c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-10-13T12:53:21.068084Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:53:21.068474Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c665390cd9eacc14","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.32:2379]}","request-path":"/0/members/c665390cd9eacc14/attributes","cluster-id":"d19b610fbe64baae","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T12:53:21.068497Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:53:21.068542Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:53:21.068987Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:53:21.069097Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:53:21.069131Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:53:21.069264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T12:53:21.069413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T12:53:21.072112Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T12:53:21.07318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.32:2379"} {"level":"warn","ts":"2026-10-13T12:53:39.603826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.764685ms","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-13T12:53:39.603954Z","caller":"traceutil/trace.go:171","msg":"trace[1868704111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"220.91893ms","start":"2026-10-13T12:53:39.383011Z","end":"2026-10-13T12:53:39.60393Z","steps":["trace[1868704111] 'range keys from in-memory index tree' (duration: 220.624171ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:54:11.538835Z","caller":"traceutil/trace.go:171","msg":"trace[870917580] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"149.160211ms","start":"2026-10-13T12:54:11.38964Z","end":"2026-10-13T12:54:11.5388Z","steps":["trace[870917580] 'read index received' (duration: 148.939245ms)","trace[870917580] 'applied index is now lower than readState.Index' (duration: 219.676µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:54:11.538964Z","caller":"traceutil/trace.go:171","msg":"trace[1737425252] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"195.977435ms","start":"2026-10-13T12:54:11.342975Z","end":"2026-10-13T12:54:11.538952Z","steps":["trace[1737425252] 'process raft request' (duration: 195.657846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:54:11.5393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.642164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jnvv8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-13T12:54:11.539368Z","caller":"traceutil/trace.go:171","msg":"trace[175870818] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jnvv8; range_end:; response_count:1; response_revision:647; }","duration":"149.766228ms","start":"2026-10-13T12:54:11.389585Z","end":"2026-10-13T12:54:11.539351Z","steps":["trace[175870818] 'agreement among raft nodes before linearized reading' (duration: 149.519991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:54:11.898142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.2115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:54:11.8983Z","caller":"traceutil/trace.go:171","msg":"trace[703820154] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"359.352117ms","start":"2026-10-13T12:54:11.538922Z","end":"2026-10-13T12:54:11.898274Z","steps":["trace[703820154] 'read index received' (duration: 103.192281ms)","trace[703820154] 'applied index is now lower than readState.Index' (duration: 256.157726ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:54:11.898689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.418682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-13T12:54:11.898745Z","caller":"traceutil/trace.go:171","msg":"trace[1066139637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"416.554746ms","start":"2026-10-13T12:54:11.482174Z","end":"2026-10-13T12:54:11.898729Z","steps":["trace[1066139637] 'agreement among raft nodes before linearized reading' (duration: 416.200406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:54:11.89879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:54:11.482152Z","time spent":"416.624508ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-13T12:54:11.898355Z","caller":"traceutil/trace.go:171","msg":"trace[23290179] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"467.848453ms","start":"2026-10-13T12:54:11.430453Z","end":"2026-10-13T12:54:11.898301Z","steps":["trace[23290179] 'process raft request' (duration: 211.81677ms)","trace[23290179] 'compare' (duration: 254.82192ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:54:11.899374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:54:11.430416Z","time spent":"468.83806ms","remote":"127.0.0.1:37734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T12:54:12.072175Z","caller":"traceutil/trace.go:171","msg":"trace[1949173811] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"173.779326ms","start":"2026-10-13T12:54:11.898341Z","end":"2026-10-13T12:54:12.072121Z","steps":["trace[1949173811] 'read index received' (duration: 118.055388ms)","trace[1949173811] 'applied index is now lower than readState.Index' (duration: 55.720918ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:54:12.071989Z","caller":"traceutil/trace.go:171","msg":"trace[329197267] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"509.737551ms","start":"2026-10-13T12:54:11.562185Z","end":"2026-10-13T12:54:12.071922Z","steps":["trace[329197267] 'process raft request' (duration: 454.194827ms)","trace[329197267] 'compare' (duration: 54.984929ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:54:12.072334Z","caller":"traceutil/trace.go:171","msg":"trace[1394037016] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"168.273406ms","start":"2026-10-13T12:54:11.904Z","end":"2026-10-13T12:54:12.072274Z","steps":["trace[1394037016] 'process raft request' (duration: 167.862184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:54:12.072496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.017727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-13T12:54:12.072544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:54:11.562144Z","time spent":"510.246086ms","remote":"127.0.0.1:37830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T12:54:12.072583Z","caller":"traceutil/trace.go:171","msg":"trace[1930443372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"184.179583ms","start":"2026-10-13T12:54:11.888384Z","end":"2026-10-13T12:54:12.072564Z","steps":["trace[1930443372] 'agreement among raft nodes before linearized reading' (duration: 183.922796ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:54:56.515969Z","caller":"traceutil/trace.go:171","msg":"trace[1721037707] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"201.687173ms","start":"2026-10-13T12:54:56.314249Z","end":"2026-10-13T12:54:56.515936Z","steps":["trace[1721037707] 'process raft request' (duration: 201.514778ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:54:56.549245Z","caller":"traceutil/trace.go:171","msg":"trace[638851050] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"233.568997ms","start":"2026-10-13T12:54:56.315649Z","end":"2026-10-13T12:54:56.549218Z","steps":["trace[638851050] 'process raft request' (duration: 233.348591ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:54:56.549365Z","caller":"traceutil/trace.go:171","msg":"trace[1609650845] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"186.331602ms","start":"2026-10-13T12:54:56.363008Z","end":"2026-10-13T12:54:56.549339Z","steps":["trace[1609650845] 'process raft request' (duration: 186.130867ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:55:42.595243Z","caller":"traceutil/trace.go:171","msg":"trace[1052129403] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"163.972861ms","start":"2026-10-13T12:55:42.431245Z","end":"2026-10-13T12:55:42.595218Z","steps":["trace[1052129403] 'process raft request' (duration: 163.834477ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:55:42.950415Z","caller":"traceutil/trace.go:171","msg":"trace[1512397045] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"194.727767ms","start":"2026-10-13T12:55:42.755627Z","end":"2026-10-13T12:55:42.950354Z","steps":["trace[1512397045] 'read index received' (duration: 166.213093ms)","trace[1512397045] 'applied index is now lower than readState.Index' (duration: 28.513474ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:55:42.950588Z","caller":"traceutil/trace.go:171","msg":"trace[999319567] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"195.596441ms","start":"2026-10-13T12:55:42.754969Z","end":"2026-10-13T12:55:42.950566Z","steps":["trace[999319567] 'process raft request' (duration: 166.924182ms)","trace[999319567] 'compare' (duration: 28.3311ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:55:42.951005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.351384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-13T12:55:42.951094Z","caller":"traceutil/trace.go:171","msg":"trace[1535017499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1888; }","duration":"195.482668ms","start":"2026-10-13T12:55:42.755587Z","end":"2026-10-13T12:55:42.951069Z","steps":["trace[1535017499] 'agreement among raft nodes before linearized reading' (duration: 195.218941ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:55:42.986016Z","caller":"traceutil/trace.go:171","msg":"trace[1340277181] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"145.499613ms","start":"2026-10-13T12:55:42.840485Z","end":"2026-10-13T12:55:42.985985Z","steps":["trace[1340277181] 'process raft request' (duration: 145.242246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:55:42.9861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.365379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f097e774-ca31-4eef-931d-d1b48ea461ed\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-13T12:55:42.986192Z","caller":"traceutil/trace.go:171","msg":"trace[757650780] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f097e774-ca31-4eef-931d-d1b48ea461ed; range_end:; response_count:1; response_revision:1889; }","duration":"113.503113ms","start":"2026-10-13T12:55:42.872666Z","end":"2026-10-13T12:55:42.986169Z","steps":["trace[757650780] 'agreement among raft nodes before linearized reading' (duration: 113.268427ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:55:59.151448Z","caller":"traceutil/trace.go:171","msg":"trace[1705424161] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"113.996117ms","start":"2026-10-13T12:55:59.03742Z","end":"2026-10-13T12:55:59.151416Z","steps":["trace[1705424161] 'process raft request' (duration: 113.810812ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:55:59.155078Z","caller":"traceutil/trace.go:171","msg":"trace[1987163474] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"110.095379ms","start":"2026-10-13T12:55:59.044964Z","end":"2026-10-13T12:55:59.155059Z","steps":["trace[1987163474] 'process raft request' (duration: 109.921974ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:55:59.480746Z","caller":"traceutil/trace.go:171","msg":"trace[178337087] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"119.745845ms","start":"2026-10-13T12:55:59.360982Z","end":"2026-10-13T12:55:59.480728Z","steps":["trace[178337087] 'process raft request' (duration: 119.617361ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:56:38.988439Z","caller":"traceutil/trace.go:171","msg":"trace[1804056299] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"165.491133ms","start":"2026-10-13T12:56:38.822919Z","end":"2026-10-13T12:56:38.98841Z","steps":["trace[1804056299] 'process raft request' (duration: 165.275838ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:21.584824Z","caller":"traceutil/trace.go:171","msg":"trace[1112235392] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"126.007779ms","start":"2026-10-13T12:57:21.458785Z","end":"2026-10-13T12:57:21.584793Z","steps":["trace[1112235392] 'process raft request' (duration: 125.798964ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:21.588712Z","caller":"traceutil/trace.go:171","msg":"trace[864669230] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"112.421246ms","start":"2026-10-13T12:57:21.476261Z","end":"2026-10-13T12:57:21.588683Z","steps":["trace[864669230] 'process raft request' (duration: 112.1953ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:49.526484Z","caller":"traceutil/trace.go:171","msg":"trace[874358899] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"165.402533ms","start":"2026-10-13T12:57:49.361052Z","end":"2026-10-13T12:57:49.526455Z","steps":["trace[874358899] 'process raft request' (duration: 164.740115ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:57:55.456824Z","caller":"traceutil/trace.go:171","msg":"trace[1635973962] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"134.818326ms","start":"2026-10-13T12:57:55.321978Z","end":"2026-10-13T12:57:55.456796Z","steps":["trace[1635973962] 'process raft request' (duration: 134.654261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:36.868114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.809735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T13:02:36.868292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.405925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T13:02:36.868332Z","caller":"traceutil/trace.go:171","msg":"trace[1528699924] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5248; }","duration":"231.512438ms","start":"2026-10-13T13:02:36.636809Z","end":"2026-10-13T13:02:36.868321Z","steps":["trace[1528699924] 'count revisions from in-memory index tree' (duration: 231.278811ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:36.868293Z","caller":"traceutil/trace.go:171","msg":"trace[1849176450] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:5248; }","duration":"248.091353ms","start":"2026-10-13T13:02:36.620182Z","end":"2026-10-13T13:02:36.868274Z","steps":["trace[1849176450] 'range keys from in-memory index tree' (duration: 247.674522ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:36.879556Z","caller":"traceutil/trace.go:171","msg":"trace[450231845] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"166.866043ms","start":"2026-10-13T13:02:36.712675Z","end":"2026-10-13T13:02:36.879541Z","steps":["trace[450231845] 'process raft request' (duration: 166.75802ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:36.879611Z","caller":"traceutil/trace.go:171","msg":"trace[247444606] linearizableReadLoop","detail":"{readStateIndex:5446; appliedIndex:5446; }","duration":"140.958911ms","start":"2026-10-13T13:02:36.738629Z","end":"2026-10-13T13:02:36.879588Z","steps":["trace[247444606] 'read index received' (duration: 140.947521ms)","trace[247444606] 'applied index is now lower than readState.Index' (duration: 9.56µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:02:36.879733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.088564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T13:02:36.879764Z","caller":"traceutil/trace.go:171","msg":"trace[1577816186] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5249; }","duration":"141.165016ms","start":"2026-10-13T13:02:36.738588Z","end":"2026-10-13T13:02:36.879753Z","steps":["trace[1577816186] 'agreement among raft nodes before linearized reading' (duration: 141.092774ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:36.886517Z","caller":"traceutil/trace.go:171","msg":"trace[1472355511] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"128.140949ms","start":"2026-10-13T13:02:36.758365Z","end":"2026-10-13T13:02:36.886506Z","steps":["trace[1472355511] 'process raft request' (duration: 127.893402ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:36.886471Z","caller":"traceutil/trace.go:171","msg":"trace[1920106351] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"108.933942ms","start":"2026-10-13T13:02:36.777509Z","end":"2026-10-13T13:02:36.886443Z","steps":["trace[1920106351] 'process raft request' (duration: 108.85267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:36.886691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.41573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q5jqt\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-10-13T13:02:36.886776Z","caller":"traceutil/trace.go:171","msg":"trace[32617686] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q5jqt; range_end:; response_count:1; response_revision:5251; }","duration":"146.534604ms","start":"2026-10-13T13:02:36.740226Z","end":"2026-10-13T13:02:36.886761Z","steps":["trace[32617686] 'agreement among raft nodes before linearized reading' (duration: 146.181124ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:39.950221Z","caller":"traceutil/trace.go:171","msg":"trace[772589454] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"142.38747ms","start":"2026-10-13T13:02:39.807809Z","end":"2026-10-13T13:02:39.950197Z","steps":["trace[772589454] 'process raft request' (duration: 142.260617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:58.924381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.745336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T13:02:58.924548Z","caller":"traceutil/trace.go:171","msg":"trace[520555367] linearizableReadLoop","detail":"{readStateIndex:5901; appliedIndex:5900; }","duration":"197.858823ms","start":"2026-10-13T13:02:58.726667Z","end":"2026-10-13T13:02:58.924526Z","steps":["trace[520555367] 'read index received' (duration: 25.840449ms)","trace[520555367] 'applied index is now lower than readState.Index' (duration: 172.016554ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:02:58.924722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.055969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5fd90f7e-f673-4e7e-9575-6b404df296dd\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-13T13:02:58.924783Z","caller":"traceutil/trace.go:171","msg":"trace[966597580] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5fd90f7e-f673-4e7e-9575-6b404df296dd; range_end:; response_count:1; response_revision:5690; }","duration":"198.147452ms","start":"2026-10-13T13:02:58.726615Z","end":"2026-10-13T13:02:58.924763Z","steps":["trace[966597580] 'agreement among raft nodes before linearized reading' (duration: 197.969127ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:02:58.924632Z","caller":"traceutil/trace.go:171","msg":"trace[485062668] transaction","detail":"{read_only:false; response_revision:5690; number_of_response:1; }","duration":"198.170942ms","start":"2026-10-13T13:02:58.726427Z","end":"2026-10-13T13:02:58.924598Z","steps":["trace[485062668] 'process raft request' (duration: 26.108217ms)","trace[485062668] 'compare' (duration: 171.577182ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:02:59.346741Z","caller":"traceutil/trace.go:171","msg":"trace[1581303690] transaction","detail":"{read_only:false; response_revision:5692; number_of_response:1; }","duration":"361.627271ms","start":"2026-10-13T13:02:58.985076Z","end":"2026-10-13T13:02:59.346703Z","steps":["trace[1581303690] 'process raft request' (duration: 361.451566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:59.346893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:02:58.985056Z","time spent":"361.758945ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:02:59.346942Z","caller":"traceutil/trace.go:171","msg":"trace[1225969547] linearizableReadLoop","detail":"{readStateIndex:5904; appliedIndex:5902; }","duration":"350.974968ms","start":"2026-10-13T13:02:58.995933Z","end":"2026-10-13T13:02:59.346908Z","steps":["trace[1225969547] 'read index received' (duration: 11.043863ms)","trace[1225969547] 'applied index is now lower than readState.Index' (duration: 339.929035ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:02:59.347013Z","caller":"traceutil/trace.go:171","msg":"trace[1367822489] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"358.344311ms","start":"2026-10-13T13:02:58.988626Z","end":"2026-10-13T13:02:59.346971Z","steps":["trace[1367822489] 'process raft request' (duration: 358.036793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:59.347113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.183074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q5jqt\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-10-13T13:02:59.347146Z","caller":"traceutil/trace.go:171","msg":"trace[685773766] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q5jqt; range_end:; response_count:1; response_revision:5693; }","duration":"351.242006ms","start":"2026-10-13T13:02:58.995893Z","end":"2026-10-13T13:02:59.347135Z","steps":["trace[685773766] 'agreement among raft nodes before linearized reading' (duration: 351.117512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:59.347172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:02:58.995878Z","time spent":"351.288276ms","remote":"127.0.0.1:37830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6471,"request content":"key:\"/registry/pods/openstack/keepalived-q5jqt\" "} {"level":"warn","ts":"2026-10-13T13:02:59.347165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.249234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T13:02:59.3472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:02:58.988602Z","time spent":"358.492144ms","remote":"127.0.0.1:37900","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-13T13:02:59.347246Z","caller":"traceutil/trace.go:171","msg":"trace[1762950141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5693; }","duration":"143.372857ms","start":"2026-10-13T13:02:59.203854Z","end":"2026-10-13T13:02:59.347227Z","steps":["trace[1762950141] 'agreement among raft nodes before linearized reading' (duration: 143.241053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:02:59.347611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.601365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5fd90f7e-f673-4e7e-9575-6b404df296dd\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-13T13:02:59.347665Z","caller":"traceutil/trace.go:171","msg":"trace[1376016839] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5fd90f7e-f673-4e7e-9575-6b404df296dd; range_end:; response_count:1; response_revision:5693; }","duration":"119.705928ms","start":"2026-10-13T13:02:59.227944Z","end":"2026-10-13T13:02:59.34765Z","steps":["trace[1376016839] 'agreement among raft nodes before linearized reading' (duration: 119.253035ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:03:21.339482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2993} {"level":"info","ts":"2026-10-13T13:03:21.422904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2993,"took":"81.864938ms","hash":3971286510,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":32452608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T13:03:21.42298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3971286510,"revision":2993,"compact-revision":-1} {"level":"info","ts":"2026-10-13T13:08:21.347514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5912} {"level":"info","ts":"2026-10-13T13:08:21.452933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5912,"took":"102.570468ms","hash":3181125038,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":26386432,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-13T13:08:21.453027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3181125038,"revision":5912,"compact-revision":2993} {"level":"warn","ts":"2026-10-13T13:09:27.25128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.570292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T13:09:27.25145Z","caller":"traceutil/trace.go:171","msg":"trace[1380719833] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"331.733092ms","start":"2026-10-13T13:09:26.919697Z","end":"2026-10-13T13:09:27.25143Z","steps":["trace[1380719833] 'process raft request' (duration: 64.697808ms)","trace[1380719833] 'compare' (duration: 266.020026ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:09:27.251726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:09:26.919672Z","time spent":"331.943567ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:09:40.136982Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002336.wal"} {"level":"warn","ts":"2026-10-13T13:09:57.305848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.196177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T13:09:57.306094Z","caller":"traceutil/trace.go:171","msg":"trace[1153185182] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"283.191299ms","start":"2026-10-13T13:09:57.022876Z","end":"2026-10-13T13:09:57.306068Z","steps":["trace[1153185182] 'process raft request' (duration: 89.675743ms)","trace[1153185182] 'compare' (duration: 193.013652ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:09:59.931395Z","caller":"traceutil/trace.go:171","msg":"trace[2130562093] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"150.093513ms","start":"2026-10-13T13:09:59.781255Z","end":"2026-10-13T13:09:59.931349Z","steps":["trace[2130562093] 'process raft request' (duration: 150.034361ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:09:59.931397Z","caller":"traceutil/trace.go:171","msg":"trace[411624216] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"150.307839ms","start":"2026-10-13T13:09:59.78104Z","end":"2026-10-13T13:09:59.931348Z","steps":["trace[411624216] 'process raft request' (duration: 80.207463ms)","trace[411624216] 'compare' (duration: 69.905281ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:10:08.019551Z","caller":"traceutil/trace.go:171","msg":"trace[1789644511] transaction","detail":"{read_only:false; response_revision:8904; number_of_response:1; }","duration":"107.595705ms","start":"2026-10-13T13:10:07.911916Z","end":"2026-10-13T13:10:08.019512Z","steps":["trace[1789644511] 'process raft request' (duration: 90.398723ms)","trace[1789644511] 'compare' (duration: 16.932195ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:11:19.779074Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T13:11:19.785088Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-13T13:11:19.785174Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-13T13:11:33.174115Z","caller":"traceutil/trace.go:171","msg":"trace[913716268] transaction","detail":"{read_only:false; response_revision:9736; number_of_response:1; }","duration":"141.705742ms","start":"2026-10-13T13:11:33.03239Z","end":"2026-10-13T13:11:33.174096Z","steps":["trace[913716268] 'process raft request' (duration: 141.513636ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:13:21.356767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8085} {"level":"info","ts":"2026-10-13T13:13:21.439017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8085,"took":"80.062609ms","hash":3431876940,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":28405760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T13:13:21.439116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3431876940,"revision":8085,"compact-revision":5912} {"level":"info","ts":"2026-10-13T13:17:09.952076Z","caller":"traceutil/trace.go:171","msg":"trace[931699706] transaction","detail":"{read_only:false; response_revision:12163; number_of_response:1; }","duration":"132.442537ms","start":"2026-10-13T13:17:09.819596Z","end":"2026-10-13T13:17:09.952039Z","steps":["trace[931699706] 'process raft request' (duration: 132.19784ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:17:11.179585Z","caller":"traceutil/trace.go:171","msg":"trace[1071375523] transaction","detail":"{read_only:false; response_revision:12170; number_of_response:1; }","duration":"101.867998ms","start":"2026-10-13T13:17:11.077653Z","end":"2026-10-13T13:17:11.179521Z","steps":["trace[1071375523] 'process raft request' (duration: 101.496948ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:18:21.364853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10488} {"level":"info","ts":"2026-10-13T13:18:21.45799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10488,"took":"89.826068ms","hash":363881219,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":26087424,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-13T13:18:21.458111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":363881219,"revision":10488,"compact-revision":8085} {"level":"info","ts":"2026-10-13T13:23:21.375388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12646} {"level":"info","ts":"2026-10-13T13:23:21.465126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12646,"took":"86.423384ms","hash":4275939902,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":27611136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T13:23:21.465207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4275939902,"revision":12646,"compact-revision":10488} {"level":"warn","ts":"2026-10-13T13:23:54.896423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.561572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-13T13:23:54.896529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.057612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.32\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-13T13:23:54.896634Z","caller":"traceutil/trace.go:171","msg":"trace[1194149957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14856; }","duration":"112.84729ms","start":"2026-10-13T13:23:54.783758Z","end":"2026-10-13T13:23:54.896606Z","steps":["trace[1194149957] 'range keys from in-memory index tree' (duration: 112.281314ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:23:54.896648Z","caller":"traceutil/trace.go:171","msg":"trace[1020760949] range","detail":"{range_begin:/registry/masterleases/162.253.55.32; range_end:; response_count:1; response_revision:14856; }","duration":"150.243617ms","start":"2026-10-13T13:23:54.746374Z","end":"2026-10-13T13:23:54.896618Z","steps":["trace[1020760949] 'range keys from in-memory index tree' (duration: 149.776773ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:23:54.925531Z","caller":"traceutil/trace.go:171","msg":"trace[1652280317] linearizableReadLoop","detail":"{readStateIndex:15370; appliedIndex:15368; }","duration":"122.601288ms","start":"2026-10-13T13:23:54.802891Z","end":"2026-10-13T13:23:54.925492Z","steps":["trace[1652280317] 'read index received' (duration: 52.788941ms)","trace[1652280317] 'applied index is now lower than readState.Index' (duration: 69.810827ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:23:54.925716Z","caller":"traceutil/trace.go:171","msg":"trace[1797507450] transaction","detail":"{read_only:false; response_revision:14858; number_of_response:1; }","duration":"123.587885ms","start":"2026-10-13T13:23:54.802075Z","end":"2026-10-13T13:23:54.925663Z","steps":["trace[1797507450] 'process raft request' (duration: 123.229935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:23:54.925818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.898846ms","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-13T13:23:54.925877Z","caller":"traceutil/trace.go:171","msg":"trace[1768311097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14858; }","duration":"123.004869ms","start":"2026-10-13T13:23:54.802853Z","end":"2026-10-13T13:23:54.925858Z","steps":["trace[1768311097] 'agreement among raft nodes before linearized reading' (duration: 122.774183ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:23:55.648085Z","caller":"traceutil/trace.go:171","msg":"trace[1822409947] linearizableReadLoop","detail":"{readStateIndex:15375; appliedIndex:15374; }","duration":"258.330415ms","start":"2026-10-13T13:23:55.389721Z","end":"2026-10-13T13:23:55.648052Z","steps":["trace[1822409947] 'read index received' (duration: 257.989786ms)","trace[1822409947] 'applied index is now lower than readState.Index' (duration: 339.339µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:23:55.648314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.566992ms","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-13T13:23:55.648369Z","caller":"traceutil/trace.go:171","msg":"trace[338871854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14862; }","duration":"258.646444ms","start":"2026-10-13T13:23:55.389694Z","end":"2026-10-13T13:23:55.648341Z","steps":["trace[338871854] 'agreement among raft nodes before linearized reading' (duration: 258.464599ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:23:55.648298Z","caller":"traceutil/trace.go:171","msg":"trace[1034233199] transaction","detail":"{read_only:false; response_revision:14862; number_of_response:1; }","duration":"273.335218ms","start":"2026-10-13T13:23:55.37487Z","end":"2026-10-13T13:23:55.648206Z","steps":["trace[1034233199] 'process raft request' (duration: 272.952987ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:23:55.802238Z","caller":"traceutil/trace.go:171","msg":"trace[1704358244] linearizableReadLoop","detail":"{readStateIndex:15376; appliedIndex:15375; }","duration":"143.84436ms","start":"2026-10-13T13:23:55.658367Z","end":"2026-10-13T13:23:55.802212Z","steps":["trace[1704358244] 'read index received' (duration: 112.759817ms)","trace[1704358244] 'applied index is now lower than readState.Index' (duration: 31.083433ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:23:55.802399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.000206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T13:23:55.802438Z","caller":"traceutil/trace.go:171","msg":"trace[771487049] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:14863; }","duration":"144.068057ms","start":"2026-10-13T13:23:55.65836Z","end":"2026-10-13T13:23:55.802428Z","steps":["trace[771487049] 'agreement among raft nodes before linearized reading' (duration: 143.947404ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:23:55.802853Z","caller":"traceutil/trace.go:171","msg":"trace[17020060] transaction","detail":"{read_only:false; response_revision:14863; number_of_response:1; }","duration":"148.89551ms","start":"2026-10-13T13:23:55.653945Z","end":"2026-10-13T13:23:55.80284Z","steps":["trace[17020060] 'process raft request' (duration: 117.2299ms)","trace[17020060] 'compare' (duration: 30.892819ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:24:18.747312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.91179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-13T13:24:18.747399Z","caller":"traceutil/trace.go:171","msg":"trace[179923461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15174; }","duration":"141.065375ms","start":"2026-10-13T13:24:18.606313Z","end":"2026-10-13T13:24:18.747379Z","steps":["trace[179923461] 'range keys from in-memory index tree' (duration: 140.733305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:24:19.010152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.989004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T13:24:19.010253Z","caller":"traceutil/trace.go:171","msg":"trace[668528684] linearizableReadLoop","detail":"{readStateIndex:15701; appliedIndex:15700; }","duration":"180.267442ms","start":"2026-10-13T13:24:18.829969Z","end":"2026-10-13T13:24:19.010236Z","steps":["trace[668528684] 'read index received' (duration: 63.035022ms)","trace[668528684] 'applied index is now lower than readState.Index' (duration: 117.23146ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:24:19.01039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.450187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-13T13:24:19.010417Z","caller":"traceutil/trace.go:171","msg":"trace[281942693] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15176; }","duration":"180.511218ms","start":"2026-10-13T13:24:18.829895Z","end":"2026-10-13T13:24:19.010406Z","steps":["trace[281942693] 'agreement among raft nodes before linearized reading' (duration: 180.382575ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:24:19.010463Z","caller":"traceutil/trace.go:171","msg":"trace[1194812867] transaction","detail":"{read_only:false; response_revision:15176; number_of_response:1; }","duration":"257.42084ms","start":"2026-10-13T13:24:18.752981Z","end":"2026-10-13T13:24:19.010402Z","steps":["trace[1194812867] 'process raft request' (duration: 140.098048ms)","trace[1194812867] 'compare' (duration: 116.849489ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:25:35.181565Z","caller":"traceutil/trace.go:171","msg":"trace[566890237] linearizableReadLoop","detail":"{readStateIndex:16484; appliedIndex:16483; }","duration":"183.435386ms","start":"2026-10-13T13:25:34.998092Z","end":"2026-10-13T13:25:35.181528Z","steps":["trace[566890237] 'read index received' (duration: 183.222681ms)","trace[566890237] 'applied index is now lower than readState.Index' (duration: 210.825µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:25:35.181671Z","caller":"traceutil/trace.go:171","msg":"trace[588869213] transaction","detail":"{read_only:false; response_revision:15938; number_of_response:1; }","duration":"233.741079ms","start":"2026-10-13T13:25:34.947868Z","end":"2026-10-13T13:25:35.181609Z","steps":["trace[588869213] 'process raft request' (duration: 233.44496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.182129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.031153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:22 size:197645"} {"level":"info","ts":"2026-10-13T13:25:35.182185Z","caller":"traceutil/trace.go:171","msg":"trace[1530193124] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:22; response_revision:15938; }","duration":"184.152816ms","start":"2026-10-13T13:25:34.998017Z","end":"2026-10-13T13:25:35.18217Z","steps":["trace[1530193124] 'agreement among raft nodes before linearized reading' (duration: 183.646102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.538276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.006424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T13:25:35.538407Z","caller":"traceutil/trace.go:171","msg":"trace[201743086] linearizableReadLoop","detail":"{readStateIndex:16485; appliedIndex:16484; }","duration":"175.510809ms","start":"2026-10-13T13:25:35.362875Z","end":"2026-10-13T13:25:35.538386Z","steps":["trace[201743086] 'read index received' (duration: 38.691µs)","trace[201743086] 'applied index is now lower than readState.Index' (duration: 175.470148ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:25:35.538464Z","caller":"traceutil/trace.go:171","msg":"trace[1011994580] transaction","detail":"{read_only:false; response_revision:15939; number_of_response:1; }","duration":"233.305086ms","start":"2026-10-13T13:25:35.305116Z","end":"2026-10-13T13:25:35.538421Z","steps":["trace[1011994580] 'process raft request' (duration: 38.033214ms)","trace[1011994580] 'compare' (duration: 194.83616ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:25:35.538536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.634013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T13:25:35.538652Z","caller":"traceutil/trace.go:171","msg":"trace[498401689] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15939; }","duration":"175.804067ms","start":"2026-10-13T13:25:35.36282Z","end":"2026-10-13T13:25:35.538624Z","steps":["trace[498401689] 'agreement among raft nodes before linearized reading' (duration: 175.619562ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:25:35.748576Z","caller":"traceutil/trace.go:171","msg":"trace[1024143742] linearizableReadLoop","detail":"{readStateIndex:16486; appliedIndex:16485; }","duration":"210.095719ms","start":"2026-10-13T13:25:35.53844Z","end":"2026-10-13T13:25:35.748535Z","steps":["trace[1024143742] 'read index received' (duration: 118.242416ms)","trace[1024143742] 'applied index is now lower than readState.Index' (duration: 91.852263ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:25:35.748775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.528803ms","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-13T13:25:35.748856Z","caller":"traceutil/trace.go:171","msg":"trace[1616864655] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15940; }","duration":"323.650287ms","start":"2026-10-13T13:25:35.425186Z","end":"2026-10-13T13:25:35.748836Z","steps":["trace[1616864655] 'agreement among raft nodes before linearized reading' (duration: 323.40498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.748911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:25:35.425166Z","time spent":"323.731749ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5472,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-13T13:25:35.748957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.7297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-13T13:25:35.749008Z","caller":"traceutil/trace.go:171","msg":"trace[1420142149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15940; }","duration":"346.870304ms","start":"2026-10-13T13:25:35.402128Z","end":"2026-10-13T13:25:35.748999Z","steps":["trace[1420142149] 'agreement among raft nodes before linearized reading' (duration: 346.560406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.74902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.641026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-13T13:25:35.749044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:25:35.402116Z","time spent":"346.916985ms","remote":"127.0.0.1:37900","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-13T13:25:35.749052Z","caller":"traceutil/trace.go:171","msg":"trace[615214558] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15940; }","duration":"103.709928ms","start":"2026-10-13T13:25:35.645332Z","end":"2026-10-13T13:25:35.749042Z","steps":["trace[615214558] 'agreement among raft nodes before linearized reading' (duration: 103.614645ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:25:35.749175Z","caller":"traceutil/trace.go:171","msg":"trace[211730444] transaction","detail":"{read_only:false; response_revision:15940; number_of_response:1; }","duration":"346.902415ms","start":"2026-10-13T13:25:35.402246Z","end":"2026-10-13T13:25:35.749148Z","steps":["trace[211730444] 'process raft request' (duration: 254.336924ms)","trace[211730444] 'compare' (duration: 91.694648ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:25:35.749179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.236926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-13T13:25:35.749287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:25:35.402221Z","time spent":"346.997407ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:25:35.749279Z","caller":"traceutil/trace.go:171","msg":"trace[35704824] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15940; }","duration":"126.40198ms","start":"2026-10-13T13:25:35.62285Z","end":"2026-10-13T13:25:35.749252Z","steps":["trace[35704824] 'agreement among raft nodes before linearized reading' (duration: 126.066971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.749366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.649373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T13:25:35.749397Z","caller":"traceutil/trace.go:171","msg":"trace[1209105742] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15940; }","duration":"104.720245ms","start":"2026-10-13T13:25:35.644669Z","end":"2026-10-13T13:25:35.74939Z","steps":["trace[1209105742] 'agreement among raft nodes before linearized reading' (duration: 104.669404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.749411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.563739ms","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-13T13:25:35.749443Z","caller":"traceutil/trace.go:171","msg":"trace[2116280302] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15940; }","duration":"368.616151ms","start":"2026-10-13T13:25:35.38082Z","end":"2026-10-13T13:25:35.749436Z","steps":["trace[2116280302] 'agreement among raft nodes before linearized reading' (duration: 368.540939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.749468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:25:35.380809Z","time spent":"368.654292ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5472,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-13T13:25:35.749515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.198789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T13:25:35.749543Z","caller":"traceutil/trace.go:171","msg":"trace[1864083626] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15940; }","duration":"380.251591ms","start":"2026-10-13T13:25:35.369285Z","end":"2026-10-13T13:25:35.749537Z","steps":["trace[1864083626] 'agreement among raft nodes before linearized reading' (duration: 380.21225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.74956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:25:35.369269Z","time spent":"380.286972ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-10-13T13:25:35.749351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.435853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T13:25:35.749718Z","caller":"traceutil/trace.go:171","msg":"trace[996188598] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15940; }","duration":"382.014389ms","start":"2026-10-13T13:25:35.367678Z","end":"2026-10-13T13:25:35.749692Z","steps":["trace[996188598] 'agreement among raft nodes before linearized reading' (duration: 381.101614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:25:35.74982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:25:35.36766Z","time spent":"382.123942ms","remote":"127.0.0.1:37878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-13T13:28:21.382415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14671} {"level":"info","ts":"2026-10-13T13:28:21.471058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14671,"took":"85.511908ms","hash":2694633417,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":32055296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T13:28:21.47125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2694633417,"revision":14671,"compact-revision":12646} {"level":"warn","ts":"2026-10-13T13:31:42.530336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.709275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-g8llp\" ","response":"range_response_count:1 size:18791"} {"level":"info","ts":"2026-10-13T13:31:42.530425Z","caller":"traceutil/trace.go:171","msg":"trace[321811208] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-g8llp; range_end:; response_count:1; response_revision:18618; }","duration":"147.877259ms","start":"2026-10-13T13:31:42.382529Z","end":"2026-10-13T13:31:42.530406Z","steps":["trace[321811208] 'range keys from in-memory index tree' (duration: 147.495289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:31:42.530808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.967071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hp67s\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-10-13T13:31:42.530837Z","caller":"traceutil/trace.go:171","msg":"trace[616105999] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hp67s; range_end:; response_count:1; response_revision:18618; }","duration":"168.023323ms","start":"2026-10-13T13:31:42.362806Z","end":"2026-10-13T13:31:42.530829Z","steps":["trace[616105999] 'range keys from in-memory index tree' (duration: 167.861308ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:31:49.487077Z","caller":"traceutil/trace.go:171","msg":"trace[988744498] linearizableReadLoop","detail":"{readStateIndex:19294; appliedIndex:19293; }","duration":"151.130589ms","start":"2026-10-13T13:31:49.335927Z","end":"2026-10-13T13:31:49.487058Z","steps":["trace[988744498] 'read index received' (duration: 151.013305ms)","trace[988744498] 'applied index is now lower than readState.Index' (duration: 116.484µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:31:49.487103Z","caller":"traceutil/trace.go:171","msg":"trace[533183680] transaction","detail":"{read_only:false; response_revision:18658; number_of_response:1; }","duration":"255.559996ms","start":"2026-10-13T13:31:49.231526Z","end":"2026-10-13T13:31:49.487086Z","steps":["trace[533183680] 'process raft request' (duration: 255.395241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:31:49.487241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.300633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9kjwg\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-10-13T13:31:49.487258Z","caller":"traceutil/trace.go:171","msg":"trace[875286242] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9kjwg; range_end:; response_count:1; response_revision:18658; }","duration":"151.366545ms","start":"2026-10-13T13:31:49.335887Z","end":"2026-10-13T13:31:49.487254Z","steps":["trace[875286242] 'agreement among raft nodes before linearized reading' (duration: 151.243222ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:31:49.548923Z","caller":"traceutil/trace.go:171","msg":"trace[1786851581] transaction","detail":"{read_only:false; response_revision:18659; number_of_response:1; }","duration":"120.497368ms","start":"2026-10-13T13:31:49.428384Z","end":"2026-10-13T13:31:49.548881Z","steps":["trace[1786851581] 'process raft request' (duration: 120.191369ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:31:49.812645Z","caller":"traceutil/trace.go:171","msg":"trace[803224798] transaction","detail":"{read_only:false; response_revision:18660; number_of_response:1; }","duration":"240.79502ms","start":"2026-10-13T13:31:49.571761Z","end":"2026-10-13T13:31:49.812556Z","steps":["trace[803224798] 'process raft request' (duration: 240.603714ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:32:51.383597Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T13:32:51.389711Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-13T13:32:51.389835Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-13T13:33:21.390117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17124} {"level":"info","ts":"2026-10-13T13:33:21.504608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17124,"took":"110.952815ms","hash":2888835224,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":33751040,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-13T13:33:21.504691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2888835224,"revision":17124,"compact-revision":14671} {"level":"warn","ts":"2026-10-13T13:37:45.102041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.946819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:37:45.102517Z","caller":"traceutil/trace.go:171","msg":"trace[807516853] transaction","detail":"{read_only:false; response_revision:21392; number_of_response:1; }","duration":"148.471255ms","start":"2026-10-13T13:37:44.954019Z","end":"2026-10-13T13:37:45.10249Z","steps":["trace[807516853] 'process raft request' (duration: 148.28386ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:37:45.102731Z","caller":"traceutil/trace.go:171","msg":"trace[145160646] transaction","detail":"{read_only:false; response_revision:21391; number_of_response:1; }","duration":"292.235842ms","start":"2026-10-13T13:37:44.81021Z","end":"2026-10-13T13:37:45.102445Z","steps":["trace[145160646] 'process raft request' (duration: 134.743849ms)","trace[145160646] 'compare' (duration: 156.625469ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:37:45.907069Z","caller":"traceutil/trace.go:171","msg":"trace[2080720951] linearizableReadLoop","detail":"{readStateIndex:22119; appliedIndex:22118; }","duration":"224.201525ms","start":"2026-10-13T13:37:45.682836Z","end":"2026-10-13T13:37:45.907038Z","steps":["trace[2080720951] 'read index received' (duration: 223.982129ms)","trace[2080720951] 'applied index is now lower than readState.Index' (duration: 218.006µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:37:45.90717Z","caller":"traceutil/trace.go:171","msg":"trace[1686889365] transaction","detail":"{read_only:false; response_revision:21393; number_of_response:1; }","duration":"292.098078ms","start":"2026-10-13T13:37:45.615033Z","end":"2026-10-13T13:37:45.907131Z","steps":["trace[1686889365] 'process raft request' (duration: 291.79844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:37:45.907264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.40011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:37:45.90731Z","caller":"traceutil/trace.go:171","msg":"trace[1241174647] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:21393; }","duration":"224.514593ms","start":"2026-10-13T13:37:45.682782Z","end":"2026-10-13T13:37:45.907296Z","steps":["trace[1241174647] 'agreement among raft nodes before linearized reading' (duration: 224.374549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:37:46.105971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.878172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:37:46.106385Z","caller":"traceutil/trace.go:171","msg":"trace[161782964] linearizableReadLoop","detail":"{readStateIndex:22121; appliedIndex:22119; }","duration":"186.420647ms","start":"2026-10-13T13:37:45.919935Z","end":"2026-10-13T13:37:46.106356Z","steps":["trace[161782964] 'read index received' (duration: 34.911938ms)","trace[161782964] 'applied index is now lower than readState.Index' (duration: 151.507339ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:37:46.106422Z","caller":"traceutil/trace.go:171","msg":"trace[1027412828] transaction","detail":"{read_only:false; response_revision:21395; number_of_response:1; }","duration":"381.582725ms","start":"2026-10-13T13:37:45.724802Z","end":"2026-10-13T13:37:46.106385Z","steps":["trace[1027412828] 'process raft request' (duration: 381.303727ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:37:46.106413Z","caller":"traceutil/trace.go:171","msg":"trace[1233080977] transaction","detail":"{read_only:false; response_revision:21394; number_of_response:1; }","duration":"411.389523ms","start":"2026-10-13T13:37:45.694926Z","end":"2026-10-13T13:37:46.106315Z","steps":["trace[1233080977] 'process raft request' (duration: 260.046208ms)","trace[1233080977] 'compare' (duration: 150.689547ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:37:46.106595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:37:45.724753Z","time spent":"381.739959ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:37:46.106595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.647393ms","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-13T13:37:46.106652Z","caller":"traceutil/trace.go:171","msg":"trace[937459817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21395; }","duration":"186.741206ms","start":"2026-10-13T13:37:45.919897Z","end":"2026-10-13T13:37:46.106638Z","steps":["trace[937459817] 'agreement among raft nodes before linearized reading' (duration: 186.551471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:37:46.107114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:37:45.694881Z","time spent":"411.703842ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:37:46.416688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.690367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:37:46.41714Z","caller":"traceutil/trace.go:171","msg":"trace[651968341] transaction","detail":"{read_only:false; response_revision:21397; number_of_response:1; }","duration":"305.705712ms","start":"2026-10-13T13:37:46.111411Z","end":"2026-10-13T13:37:46.417117Z","steps":["trace[651968341] 'process raft request' (duration: 146.502842ms)","trace[651968341] 'compare' (duration: 158.420169ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:37:46.417268Z","caller":"traceutil/trace.go:171","msg":"trace[581011894] linearizableReadLoop","detail":"{readStateIndex:22124; appliedIndex:22122; }","duration":"211.599268ms","start":"2026-10-13T13:37:46.205635Z","end":"2026-10-13T13:37:46.417235Z","steps":["trace[581011894] 'read index received' (duration: 52.222483ms)","trace[581011894] 'applied index is now lower than readState.Index' (duration: 159.373825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:37:46.417222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:37:46.111389Z","time spent":"305.796065ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:37:46.417422Z","caller":"traceutil/trace.go:171","msg":"trace[1576008432] transaction","detail":"{read_only:false; response_revision:21398; number_of_response:1; }","duration":"305.531978ms","start":"2026-10-13T13:37:46.11183Z","end":"2026-10-13T13:37:46.417362Z","steps":["trace[1576008432] 'process raft request' (duration: 304.971992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:37:46.41749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.827905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:37:46.417547Z","caller":"traceutil/trace.go:171","msg":"trace[153489590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21398; }","duration":"211.904757ms","start":"2026-10-13T13:37:46.205626Z","end":"2026-10-13T13:37:46.417531Z","steps":["trace[153489590] 'agreement among raft nodes before linearized reading' (duration: 211.785254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:37:46.417666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:37:46.111804Z","time spent":"305.721282ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:37:46.417884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.207076ms","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-13T13:37:46.417968Z","caller":"traceutil/trace.go:171","msg":"trace[1750982613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21398; }","duration":"161.335169ms","start":"2026-10-13T13:37:46.256616Z","end":"2026-10-13T13:37:46.417951Z","steps":["trace[1750982613] 'agreement among raft nodes before linearized reading' (duration: 160.880856ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:38:21.407111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19582} {"level":"info","ts":"2026-10-13T13:38:21.527776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19582,"took":"118.104042ms","hash":969561,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":28925952,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-13T13:38:21.527865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":969561,"revision":19582,"compact-revision":17124} {"level":"warn","ts":"2026-10-13T13:41:52.094094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.495263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T13:41:52.094216Z","caller":"traceutil/trace.go:171","msg":"trace[189737796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23926; }","duration":"165.643247ms","start":"2026-10-13T13:41:51.928539Z","end":"2026-10-13T13:41:52.094182Z","steps":["trace[189737796] 'range keys from in-memory index tree' (duration: 165.297577ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:43:21.41983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21645} {"level":"info","ts":"2026-10-13T13:43:21.530805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21645,"took":"107.130831ms","hash":1879756009,"current-db-size-bytes":57610240,"current-db-size":"58 MB","current-db-size-in-use-bytes":42995712,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-13T13:43:21.530876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1879756009,"revision":21645,"compact-revision":19582} {"level":"info","ts":"2026-10-13T13:45:20.859553Z","caller":"traceutil/trace.go:171","msg":"trace[424589202] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"136.395364ms","start":"2026-10-13T13:45:20.723132Z","end":"2026-10-13T13:45:20.859527Z","steps":["trace[424589202] 'process raft request' (duration: 136.218169ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:20.878355Z","caller":"traceutil/trace.go:171","msg":"trace[886787939] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"152.809945ms","start":"2026-10-13T13:45:20.725514Z","end":"2026-10-13T13:45:20.878324Z","steps":["trace[886787939] 'process raft request' (duration: 152.598959ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.045966Z","caller":"traceutil/trace.go:171","msg":"trace[318369531] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"179.2217ms","start":"2026-10-13T13:45:20.866716Z","end":"2026-10-13T13:45:21.045938Z","steps":["trace[318369531] 'process raft request' (duration: 179.074206ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.047078Z","caller":"traceutil/trace.go:171","msg":"trace[204139920] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"164.394543ms","start":"2026-10-13T13:45:20.882666Z","end":"2026-10-13T13:45:21.04706Z","steps":["trace[204139920] 'process raft request' (duration: 164.185717ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.047342Z","caller":"traceutil/trace.go:171","msg":"trace[168652075] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"122.564444ms","start":"2026-10-13T13:45:20.92473Z","end":"2026-10-13T13:45:21.047294Z","steps":["trace[168652075] 'process raft request' (duration: 122.245955ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.047353Z","caller":"traceutil/trace.go:171","msg":"trace[1631565872] linearizableReadLoop","detail":"{readStateIndex:26889; appliedIndex:26887; }","duration":"130.719698ms","start":"2026-10-13T13:45:20.916557Z","end":"2026-10-13T13:45:21.047277Z","steps":["trace[1631565872] 'read index received' (duration: 129.30114ms)","trace[1631565872] 'applied index is now lower than readState.Index' (duration: 1.398818ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:21.047577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.997046ms","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-13T13:45:21.047644Z","caller":"traceutil/trace.go:171","msg":"trace[1700323213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26032; }","duration":"131.108969ms","start":"2026-10-13T13:45:20.916519Z","end":"2026-10-13T13:45:21.047628Z","steps":["trace[1700323213] 'agreement among raft nodes before linearized reading' (duration: 130.888843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:21.047726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.585108ms","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-13T13:45:21.047772Z","caller":"traceutil/trace.go:171","msg":"trace[1720566636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26032; }","duration":"105.681231ms","start":"2026-10-13T13:45:20.942078Z","end":"2026-10-13T13:45:21.047759Z","steps":["trace[1720566636] 'agreement among raft nodes before linearized reading' (duration: 105.520496ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.293895Z","caller":"traceutil/trace.go:171","msg":"trace[780203696] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"241.312183ms","start":"2026-10-13T13:45:21.052553Z","end":"2026-10-13T13:45:21.293865Z","steps":["trace[780203696] 'process raft request' (duration: 241.207991ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.293974Z","caller":"traceutil/trace.go:171","msg":"trace[1751228756] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"241.286692ms","start":"2026-10-13T13:45:21.052581Z","end":"2026-10-13T13:45:21.293868Z","steps":["trace[1751228756] 'process raft request' (duration: 241.005925ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:21.294053Z","caller":"traceutil/trace.go:171","msg":"trace[132366004] linearizableReadLoop","detail":"{readStateIndex:26894; appliedIndex:26891; }","duration":"134.10909ms","start":"2026-10-13T13:45:21.159918Z","end":"2026-10-13T13:45:21.294027Z","steps":["trace[132366004] 'read index received' (duration: 133.676568ms)","trace[132366004] 'applied index is now lower than readState.Index' (duration: 430.812µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:21.29408Z","caller":"traceutil/trace.go:171","msg":"trace[634075761] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"168.351911ms","start":"2026-10-13T13:45:21.125709Z","end":"2026-10-13T13:45:21.294061Z","steps":["trace[634075761] 'process raft request' (duration: 168.099724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:21.294209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.272825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T13:45:21.294255Z","caller":"traceutil/trace.go:171","msg":"trace[853944573] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26036; }","duration":"134.369648ms","start":"2026-10-13T13:45:21.159872Z","end":"2026-10-13T13:45:21.294242Z","steps":["trace[853944573] 'agreement among raft nodes before linearized reading' (duration: 134.265945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:21.598831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.970826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:21.598954Z","caller":"traceutil/trace.go:171","msg":"trace[326672896] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"299.918683ms","start":"2026-10-13T13:45:21.299009Z","end":"2026-10-13T13:45:21.598928Z","steps":["trace[326672896] 'process raft request' (duration: 86.731061ms)","trace[326672896] 'compare' (duration: 212.849283ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:21.599069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:21.298969Z","time spent":"300.053847ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:23.822716Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006926.wal"} {"level":"info","ts":"2026-10-13T13:45:25.817297Z","caller":"traceutil/trace.go:171","msg":"trace[94741340] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"199.958459ms","start":"2026-10-13T13:45:25.617314Z","end":"2026-10-13T13:45:25.817273Z","steps":["trace[94741340] 'process raft request' (duration: 199.821626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:26.208956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.850133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:26.209124Z","caller":"traceutil/trace.go:171","msg":"trace[1521765680] linearizableReadLoop","detail":"{readStateIndex:26939; appliedIndex:26937; }","duration":"248.130501ms","start":"2026-10-13T13:45:25.960979Z","end":"2026-10-13T13:45:26.20911Z","steps":["trace[1521765680] 'read index received' (duration: 59.053921ms)","trace[1521765680] 'applied index is now lower than readState.Index' (duration: 189.07547ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:26.209296Z","caller":"traceutil/trace.go:171","msg":"trace[1241457669] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"313.749943ms","start":"2026-10-13T13:45:25.895537Z","end":"2026-10-13T13:45:26.209287Z","steps":["trace[1241457669] 'process raft request' (duration: 124.494438ms)","trace[1241457669] 'compare' (duration: 188.477803ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:26.209346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:25.895517Z","time spent":"313.805144ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:26.209479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.47233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:26.209554Z","caller":"traceutil/trace.go:171","msg":"trace[2109793437] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26080; }","duration":"248.595864ms","start":"2026-10-13T13:45:25.96094Z","end":"2026-10-13T13:45:26.209536Z","steps":["trace[2109793437] 'agreement among raft nodes before linearized reading' (duration: 248.45576ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:26.209775Z","caller":"traceutil/trace.go:171","msg":"trace[615756061] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"299.854141ms","start":"2026-10-13T13:45:25.909908Z","end":"2026-10-13T13:45:26.209763Z","steps":["trace[615756061] 'process raft request' (duration: 299.154882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:26.210672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.135533ms","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-13T13:45:26.210749Z","caller":"traceutil/trace.go:171","msg":"trace[652371773] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26080; }","duration":"204.262207ms","start":"2026-10-13T13:45:26.00647Z","end":"2026-10-13T13:45:26.210733Z","steps":["trace[652371773] 'agreement among raft nodes before linearized reading' (duration: 202.860058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:26.594605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.015655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:26.594687Z","caller":"traceutil/trace.go:171","msg":"trace[1830098547] transaction","detail":"{read_only:false; response_revision:26081; number_of_response:1; }","duration":"357.053542ms","start":"2026-10-13T13:45:26.237618Z","end":"2026-10-13T13:45:26.594671Z","steps":["trace[1830098547] 'process raft request' (duration: 150.875302ms)","trace[1830098547] 'compare' (duration: 205.865901ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:26.594728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:26.237589Z","time spent":"357.118643ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:26.914058Z","caller":"traceutil/trace.go:171","msg":"trace[1921731435] linearizableReadLoop","detail":"{readStateIndex:26941; appliedIndex:26940; }","duration":"189.558403ms","start":"2026-10-13T13:45:26.724462Z","end":"2026-10-13T13:45:26.91402Z","steps":["trace[1921731435] 'read index received' (duration: 189.377979ms)","trace[1921731435] 'applied index is now lower than readState.Index' (duration: 179.174µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:26.91408Z","caller":"traceutil/trace.go:171","msg":"trace[2004125772] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"249.663473ms","start":"2026-10-13T13:45:26.664386Z","end":"2026-10-13T13:45:26.91405Z","steps":["trace[2004125772] 'process raft request' (duration: 249.439287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:26.914312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.80035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:26.914404Z","caller":"traceutil/trace.go:171","msg":"trace[2068589375] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:26082; }","duration":"189.958754ms","start":"2026-10-13T13:45:26.72442Z","end":"2026-10-13T13:45:26.914379Z","steps":["trace[2068589375] 'agreement among raft nodes before linearized reading' (duration: 189.719868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.483775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.897889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:27.483914Z","caller":"traceutil/trace.go:171","msg":"trace[515328685] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"362.671565ms","start":"2026-10-13T13:45:27.121206Z","end":"2026-10-13T13:45:27.483878Z","steps":["trace[515328685] 'process raft request' (duration: 135.591582ms)","trace[515328685] 'compare' (duration: 226.161608ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:27.484092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.121176Z","time spent":"362.807209ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:27.484265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.819239ms","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-13T13:45:27.484299Z","caller":"traceutil/trace.go:171","msg":"trace[2125145555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26086; }","duration":"319.890151ms","start":"2026-10-13T13:45:27.164398Z","end":"2026-10-13T13:45:27.484288Z","steps":["trace[2125145555] 'agreement among raft nodes before linearized reading' (duration: 319.730487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.484331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.16438Z","time spent":"319.943042ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-13T13:45:27.484082Z","caller":"traceutil/trace.go:171","msg":"trace[624335592] linearizableReadLoop","detail":"{readStateIndex:26945; appliedIndex:26944; }","duration":"319.405757ms","start":"2026-10-13T13:45:27.164448Z","end":"2026-10-13T13:45:27.483854Z","steps":["trace[624335592] 'read index received' (duration: 92.318074ms)","trace[624335592] 'applied index is now lower than readState.Index' (duration: 227.086433ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:27.857909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.244785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:27.858047Z","caller":"traceutil/trace.go:171","msg":"trace[1012606109] linearizableReadLoop","detail":"{readStateIndex:26946; appliedIndex:26945; }","duration":"373.293926ms","start":"2026-10-13T13:45:27.484736Z","end":"2026-10-13T13:45:27.85803Z","steps":["trace[1012606109] 'read index received' (duration: 207.826434ms)","trace[1012606109] 'applied index is now lower than readState.Index' (duration: 165.465992ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:27.858189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.71342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-13T13:45:27.858234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.5218ms","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-13T13:45:27.858252Z","caller":"traceutil/trace.go:171","msg":"trace[2092608620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26087; }","duration":"533.803663ms","start":"2026-10-13T13:45:27.324433Z","end":"2026-10-13T13:45:27.858236Z","steps":["trace[2092608620] 'agreement among raft nodes before linearized reading' (duration: 533.627098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.858306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.324424Z","time spent":"533.874155ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T13:45:27.858309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.078397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:27.858336Z","caller":"traceutil/trace.go:171","msg":"trace[81349927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26087; }","duration":"653.147808ms","start":"2026-10-13T13:45:27.205181Z","end":"2026-10-13T13:45:27.858329Z","steps":["trace[81349927] 'agreement among raft nodes before linearized reading' (duration: 653.103567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.858368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.205158Z","time spent":"653.20261ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T13:45:27.858264Z","caller":"traceutil/trace.go:171","msg":"trace[683703917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26087; }","duration":"535.588981ms","start":"2026-10-13T13:45:27.322666Z","end":"2026-10-13T13:45:27.858255Z","steps":["trace[683703917] 'agreement among raft nodes before linearized reading' (duration: 535.434627ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:27.858691Z","caller":"traceutil/trace.go:171","msg":"trace[21281716] transaction","detail":"{read_only:false; response_revision:26087; number_of_response:1; }","duration":"539.628892ms","start":"2026-10-13T13:45:27.319024Z","end":"2026-10-13T13:45:27.858653Z","steps":["trace[21281716] 'process raft request' (duration: 373.551844ms)","trace[21281716] 'compare' (duration: 164.765392ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:27.858741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.322607Z","time spent":"536.092686ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T13:45:27.8589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.319011Z","time spent":"539.782366ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:27.859149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.456231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:27.8592Z","caller":"traceutil/trace.go:171","msg":"trace[890233869] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26087; }","duration":"561.544244ms","start":"2026-10-13T13:45:27.297644Z","end":"2026-10-13T13:45:27.859188Z","steps":["trace[890233869] 'agreement among raft nodes before linearized reading' (duration: 561.436801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.859229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.297616Z","time spent":"561.604966ms","remote":"127.0.0.1:37730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-10-13T13:45:27.871637Z","caller":"traceutil/trace.go:171","msg":"trace[1433825060] transaction","detail":"{read_only:false; response_revision:26088; number_of_response:1; }","duration":"382.644003ms","start":"2026-10-13T13:45:27.488944Z","end":"2026-10-13T13:45:27.871588Z","steps":["trace[1433825060] 'process raft request' (duration: 382.248423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.871779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.082354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:27.871867Z","caller":"traceutil/trace.go:171","msg":"trace[1446855999] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26088; }","duration":"309.187997ms","start":"2026-10-13T13:45:27.562632Z","end":"2026-10-13T13:45:27.87182Z","steps":["trace[1446855999] 'agreement among raft nodes before linearized reading' (duration: 309.078824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:27.871857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.488919Z","time spent":"382.836759ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:27.871866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.882162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-13T13:45:27.871907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.56261Z","time spent":"309.28581ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-10-13T13:45:27.871969Z","caller":"traceutil/trace.go:171","msg":"trace[688030976] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26088; }","duration":"291.997585ms","start":"2026-10-13T13:45:27.579918Z","end":"2026-10-13T13:45:27.871916Z","steps":["trace[688030976] 'agreement among raft nodes before linearized reading' (duration: 291.772719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.277061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.334391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:28.277278Z","caller":"traceutil/trace.go:171","msg":"trace[1825866321] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"395.574558ms","start":"2026-10-13T13:45:27.881686Z","end":"2026-10-13T13:45:28.27726Z","steps":["trace[1825866321] 'process raft request' (duration: 395.530227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.277353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.881658Z","time spent":"395.65979ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:28.277382Z","caller":"traceutil/trace.go:171","msg":"trace[735497684] transaction","detail":"{read_only:false; response_revision:26090; number_of_response:1; }","duration":"414.371604ms","start":"2026-10-13T13:45:27.862975Z","end":"2026-10-13T13:45:28.277347Z","steps":["trace[735497684] 'process raft request' (duration: 414.173509ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:28.277458Z","caller":"traceutil/trace.go:171","msg":"trace[499585008] linearizableReadLoop","detail":"{readStateIndex:26949; appliedIndex:26947; }","duration":"406.035105ms","start":"2026-10-13T13:45:27.871413Z","end":"2026-10-13T13:45:28.277448Z","steps":["trace[499585008] 'read index received' (duration: 186.112498ms)","trace[499585008] 'applied index is now lower than readState.Index' (duration: 219.921657ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:28.277539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.15786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:28.277538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.862966Z","time spent":"414.482517ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:28.277571Z","caller":"traceutil/trace.go:171","msg":"trace[1526528890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26091; }","duration":"417.227033ms","start":"2026-10-13T13:45:27.860334Z","end":"2026-10-13T13:45:28.277561Z","steps":["trace[1526528890] 'agreement among raft nodes before linearized reading' (duration: 417.167292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.277596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.860316Z","time spent":"417.274694ms","remote":"127.0.0.1:37672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T13:45:28.277594Z","caller":"traceutil/trace.go:171","msg":"trace[1366626496] transaction","detail":"{read_only:false; response_revision:26089; number_of_response:1; }","duration":"414.855628ms","start":"2026-10-13T13:45:27.862693Z","end":"2026-10-13T13:45:28.277549Z","steps":["trace[1366626496] 'process raft request' (duration: 194.915171ms)","trace[1366626496] 'compare' (duration: 218.95906ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:28.278343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:27.86267Z","time spent":"415.008371ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:28.765737Z","caller":"traceutil/trace.go:171","msg":"trace[1328669484] linearizableReadLoop","detail":"{readStateIndex:26952; appliedIndex:26950; }","duration":"488.228951ms","start":"2026-10-13T13:45:28.277479Z","end":"2026-10-13T13:45:28.765708Z","steps":["trace[1328669484] 'read index received' (duration: 445.56322ms)","trace[1328669484] 'applied index is now lower than readState.Index' (duration: 42.664491ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:28.765807Z","caller":"traceutil/trace.go:171","msg":"trace[1144317501] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"551.523499ms","start":"2026-10-13T13:45:28.214218Z","end":"2026-10-13T13:45:28.765741Z","steps":["trace[1144317501] 'process raft request' (duration: 508.866748ms)","trace[1144317501] 'compare' (duration: 42.331412ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:28.765829Z","caller":"traceutil/trace.go:171","msg":"trace[1583945879] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"550.939972ms","start":"2026-10-13T13:45:28.214826Z","end":"2026-10-13T13:45:28.765765Z","steps":["trace[1583945879] 'process raft request' (duration: 550.767217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.765908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.351984ms","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-13T13:45:28.766027Z","caller":"traceutil/trace.go:171","msg":"trace[179028290] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26093; }","duration":"645.536079ms","start":"2026-10-13T13:45:28.120472Z","end":"2026-10-13T13:45:28.766008Z","steps":["trace[179028290] 'agreement among raft nodes before linearized reading' (duration: 645.329733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.76606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.214191Z","time spent":"551.721903ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:28.766081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.120451Z","time spent":"645.61306ms","remote":"127.0.0.1:36856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-13T13:45:28.766108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.214812Z","time spent":"551.107136ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:28.777744Z","caller":"traceutil/trace.go:171","msg":"trace[271372919] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"177.094891ms","start":"2026-10-13T13:45:28.600621Z","end":"2026-10-13T13:45:28.777716Z","steps":["trace[271372919] 'process raft request' (duration: 176.883355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.777765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.687636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:28.777863Z","caller":"traceutil/trace.go:171","msg":"trace[252073262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26094; }","duration":"491.85395ms","start":"2026-10-13T13:45:28.28598Z","end":"2026-10-13T13:45:28.777834Z","steps":["trace[252073262] 'agreement among raft nodes before linearized reading' (duration: 491.691606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.777947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.285961Z","time spent":"491.970943ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T13:45:28.778056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.724335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:28.778135Z","caller":"traceutil/trace.go:171","msg":"trace[1773527960] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26094; }","duration":"425.846898ms","start":"2026-10-13T13:45:28.352261Z","end":"2026-10-13T13:45:28.778108Z","steps":["trace[1773527960] 'agreement among raft nodes before linearized reading' (duration: 425.682373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:28.778189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.352246Z","time spent":"425.927471ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-10-13T13:45:29.166402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.002152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T13:45:29.166542Z","caller":"traceutil/trace.go:171","msg":"trace[1627991997] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:26094; }","duration":"266.213478ms","start":"2026-10-13T13:45:28.900295Z","end":"2026-10-13T13:45:29.166509Z","steps":["trace[1627991997] 'count revisions from in-memory index tree' (duration: 265.868019ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:29.166582Z","caller":"traceutil/trace.go:171","msg":"trace[1126367363] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"245.16832ms","start":"2026-10-13T13:45:28.921365Z","end":"2026-10-13T13:45:29.166533Z","steps":["trace[1126367363] 'process raft request' (duration: 209.129611ms)","trace[1126367363] 'compare' (duration: 35.788482ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:29.591651Z","caller":"traceutil/trace.go:171","msg":"trace[1949954673] linearizableReadLoop","detail":"{readStateIndex:26957; appliedIndex:26954; }","duration":"384.039862ms","start":"2026-10-13T13:45:29.207579Z","end":"2026-10-13T13:45:29.591618Z","steps":["trace[1949954673] 'read index received' (duration: 377.356399ms)","trace[1949954673] 'applied index is now lower than readState.Index' (duration: 6.682323ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:29.591739Z","caller":"traceutil/trace.go:171","msg":"trace[547393418] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"633.52333ms","start":"2026-10-13T13:45:28.958203Z","end":"2026-10-13T13:45:29.591726Z","steps":["trace[547393418] 'process raft request' (duration: 633.258183ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:29.591639Z","caller":"traceutil/trace.go:171","msg":"trace[427156289] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"633.812958ms","start":"2026-10-13T13:45:28.957781Z","end":"2026-10-13T13:45:29.591594Z","steps":["trace[427156289] 'process raft request' (duration: 627.30794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.591875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.957755Z","time spent":"634.032145ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:29.591885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:28.958194Z","time spent":"633.589722ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:29.592091Z","caller":"traceutil/trace.go:171","msg":"trace[404293926] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"503.023318ms","start":"2026-10-13T13:45:29.089043Z","end":"2026-10-13T13:45:29.592067Z","steps":["trace[404293926] 'process raft request' (duration: 502.523765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.592158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.949838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:29.59218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.088987Z","time spent":"503.141772ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:29.592234Z","caller":"traceutil/trace.go:171","msg":"trace[811367570] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:26098; }","duration":"263.041911ms","start":"2026-10-13T13:45:29.329151Z","end":"2026-10-13T13:45:29.592193Z","steps":["trace[811367570] 'agreement among raft nodes before linearized reading' (duration: 262.965019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.592327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.118071ms","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-13T13:45:29.592374Z","caller":"traceutil/trace.go:171","msg":"trace[1776588641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26098; }","duration":"103.213943ms","start":"2026-10-13T13:45:29.489147Z","end":"2026-10-13T13:45:29.592361Z","steps":["trace[1776588641] 'agreement among raft nodes before linearized reading' (duration: 103.007458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.592484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.031384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-13T13:45:29.592553Z","caller":"traceutil/trace.go:171","msg":"trace[1638422078] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26098; }","duration":"195.161867ms","start":"2026-10-13T13:45:29.397374Z","end":"2026-10-13T13:45:29.592535Z","steps":["trace[1638422078] 'agreement among raft nodes before linearized reading' (duration: 194.941341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.592761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.228098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:29.592806Z","caller":"traceutil/trace.go:171","msg":"trace[509280229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26098; }","duration":"385.258896ms","start":"2026-10-13T13:45:29.207532Z","end":"2026-10-13T13:45:29.592791Z","steps":["trace[509280229] 'agreement among raft nodes before linearized reading' (duration: 384.229088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.592838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.207514Z","time spent":"385.313177ms","remote":"127.0.0.1:37672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T13:45:29.974104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.402284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:29.974209Z","caller":"traceutil/trace.go:171","msg":"trace[1010683150] linearizableReadLoop","detail":"{readStateIndex:26958; appliedIndex:26957; }","duration":"377.095491ms","start":"2026-10-13T13:45:29.597095Z","end":"2026-10-13T13:45:29.974191Z","steps":["trace[1010683150] 'read index received' (duration: 224.42302ms)","trace[1010683150] 'applied index is now lower than readState.Index' (duration: 152.671041ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:29.974294Z","caller":"traceutil/trace.go:171","msg":"trace[1150297837] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"377.679797ms","start":"2026-10-13T13:45:29.596583Z","end":"2026-10-13T13:45:29.974263Z","steps":["trace[1150297837] 'process raft request' (duration: 224.954414ms)","trace[1150297837] 'compare' (duration: 152.23184ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:29.974358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.596568Z","time spent":"377.75663ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:29.97441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.278337ms","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-13T13:45:29.974511Z","caller":"traceutil/trace.go:171","msg":"trace[1422060641] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26099; }","duration":"377.419091ms","start":"2026-10-13T13:45:29.597072Z","end":"2026-10-13T13:45:29.974491Z","steps":["trace[1422060641] 'agreement among raft nodes before linearized reading' (duration: 377.207135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:29.974561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.597063Z","time spent":"377.488362ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-10-13T13:45:30.012562Z","caller":"traceutil/trace.go:171","msg":"trace[1564634624] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"148.666ms","start":"2026-10-13T13:45:29.863869Z","end":"2026-10-13T13:45:30.012535Z","steps":["trace[1564634624] 'process raft request' (duration: 148.486105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:30.807916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.137353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T13:45:30.80828Z","caller":"traceutil/trace.go:171","msg":"trace[1843266] linearizableReadLoop","detail":"{readStateIndex:26960; appliedIndex:26958; }","duration":"834.024155ms","start":"2026-10-13T13:45:29.974231Z","end":"2026-10-13T13:45:30.808256Z","steps":["trace[1843266] 'read index received' (duration: 38.228168ms)","trace[1843266] 'applied index is now lower than readState.Index' (duration: 795.793867ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:30.808353Z","caller":"traceutil/trace.go:171","msg":"trace[1374282504] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"606.753907ms","start":"2026-10-13T13:45:30.201576Z","end":"2026-10-13T13:45:30.80833Z","steps":["trace[1374282504] 'process raft request' (duration: 606.509501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:30.808464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.475199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-13T13:45:30.808517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.201549Z","time spent":"606.857429ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:30.808539Z","caller":"traceutil/trace.go:171","msg":"trace[1033615713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26101; }","duration":"928.577521ms","start":"2026-10-13T13:45:29.879924Z","end":"2026-10-13T13:45:30.808501Z","steps":["trace[1033615713] 'agreement among raft nodes before linearized reading' (duration: 928.364955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:30.808563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211376565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19107"} {"level":"warn","ts":"2026-10-13T13:45:30.808587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.879892Z","time spent":"928.682094ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-13T13:45:30.808601Z","caller":"traceutil/trace.go:171","msg":"trace[1712883626] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26101; }","duration":"1.211463518s","start":"2026-10-13T13:45:29.597126Z","end":"2026-10-13T13:45:30.80859Z","steps":["trace[1712883626] 'agreement among raft nodes before linearized reading' (duration: 1.211224771s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:30.808648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.59711Z","time spent":"1.211529929s","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19131,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-13T13:45:31.066838Z","caller":"traceutil/trace.go:171","msg":"trace[529062933] linearizableReadLoop","detail":"{readStateIndex:26964; appliedIndex:26961; }","duration":"258.485646ms","start":"2026-10-13T13:45:30.80833Z","end":"2026-10-13T13:45:31.066816Z","steps":["trace[529062933] 'read index received' (duration: 15.817585ms)","trace[529062933] 'applied index is now lower than readState.Index' (duration: 242.666601ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:31.067304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.785852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-13T13:45:31.06749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087642059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:31.067602Z","caller":"traceutil/trace.go:171","msg":"trace[1497093022] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26105; }","duration":"1.087800972s","start":"2026-10-13T13:45:29.979778Z","end":"2026-10-13T13:45:31.067579Z","steps":["trace[1497093022] 'agreement among raft nodes before linearized reading' (duration: 1.087597417s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.067304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.550115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-13T13:45:31.067677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:29.979764Z","time spent":"1.087899186s","remote":"127.0.0.1:37906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-10-13T13:45:31.067702Z","caller":"traceutil/trace.go:171","msg":"trace[172206921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26105; }","duration":"786.08119ms","start":"2026-10-13T13:45:30.281601Z","end":"2026-10-13T13:45:31.067682Z","steps":["trace[172206921] 'agreement among raft nodes before linearized reading' (duration: 785.528615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.06776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.281593Z","time spent":"786.150582ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T13:45:31.067777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.194419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:31.067801Z","caller":"traceutil/trace.go:171","msg":"trace[2056958408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26105; }","duration":"785.903595ms","start":"2026-10-13T13:45:30.281445Z","end":"2026-10-13T13:45:31.067348Z","steps":["trace[2056958408] 'agreement among raft nodes before linearized reading' (duration: 785.714ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:31.067845Z","caller":"traceutil/trace.go:171","msg":"trace[1752607611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26105; }","duration":"862.307362ms","start":"2026-10-13T13:45:30.205517Z","end":"2026-10-13T13:45:31.067824Z","steps":["trace[1752607611] 'agreement among raft nodes before linearized reading' (duration: 862.179359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.067856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.934753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-13T13:45:31.067853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.246577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:31.067852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.28142Z","time spent":"786.418149ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-13T13:45:31.067927Z","caller":"traceutil/trace.go:171","msg":"trace[228504079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26105; }","duration":"787.054436ms","start":"2026-10-13T13:45:30.280856Z","end":"2026-10-13T13:45:31.06791Z","steps":["trace[228504079] 'agreement among raft nodes before linearized reading' (duration: 786.80097ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:31.067948Z","caller":"traceutil/trace.go:171","msg":"trace[200091191] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26105; }","duration":"192.390491ms","start":"2026-10-13T13:45:30.875535Z","end":"2026-10-13T13:45:31.067926Z","steps":["trace[200091191] 'agreement among raft nodes before linearized reading' (duration: 192.232887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.067967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.280835Z","time spent":"787.123309ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T13:45:31.067973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.994668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T13:45:31.068019Z","caller":"traceutil/trace.go:171","msg":"trace[1214086137] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"295.960264ms","start":"2026-10-13T13:45:30.772025Z","end":"2026-10-13T13:45:31.067985Z","steps":["trace[1214086137] 'process raft request' (duration: 294.524955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.067855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.31977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:31.068052Z","caller":"traceutil/trace.go:171","msg":"trace[1134548988] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26105; }","duration":"782.117421ms","start":"2026-10-13T13:45:30.285916Z","end":"2026-10-13T13:45:31.068034Z","steps":["trace[1134548988] 'agreement among raft nodes before linearized reading' (duration: 782.010028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.067889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.205488Z","time spent":"862.389895ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T13:45:31.06808Z","caller":"traceutil/trace.go:171","msg":"trace[830747478] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26105; }","duration":"231.594468ms","start":"2026-10-13T13:45:30.836464Z","end":"2026-10-13T13:45:31.068058Z","steps":["trace[830747478] 'agreement among raft nodes before linearized reading' (duration: 231.302129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.068097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.285892Z","time spent":"782.189923ms","remote":"127.0.0.1:49150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":32,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-10-13T13:45:31.068121Z","caller":"traceutil/trace.go:171","msg":"trace[1469979589] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"253.735425ms","start":"2026-10-13T13:45:30.814373Z","end":"2026-10-13T13:45:31.068108Z","steps":["trace[1469979589] 'process raft request' (duration: 252.398588ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:31.068132Z","caller":"traceutil/trace.go:171","msg":"trace[108399913] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"285.681823ms","start":"2026-10-13T13:45:30.782431Z","end":"2026-10-13T13:45:31.068112Z","steps":["trace[108399913] 'process raft request' (duration: 284.284335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.067957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.43972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:31.068346Z","caller":"traceutil/trace.go:171","msg":"trace[497926656] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26105; }","duration":"491.817801ms","start":"2026-10-13T13:45:30.576505Z","end":"2026-10-13T13:45:31.068323Z","steps":["trace[497926656] 'agreement among raft nodes before linearized reading' (duration: 491.41556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.068407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.576435Z","time spent":"491.949844ms","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-10-13T13:45:31.068671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.154957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273004"} {"level":"info","ts":"2026-10-13T13:45:31.068717Z","caller":"traceutil/trace.go:171","msg":"trace[374596458] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26105; }","duration":"737.239309ms","start":"2026-10-13T13:45:30.331464Z","end":"2026-10-13T13:45:31.068703Z","steps":["trace[374596458] 'agreement among raft nodes before linearized reading' (duration: 736.817138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.068755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:30.33144Z","time spent":"737.306051ms","remote":"127.0.0.1:58770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":273028,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-10-13T13:45:31.665344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.776766ms","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-13T13:45:31.665463Z","caller":"traceutil/trace.go:171","msg":"trace[1770444191] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26105; }","duration":"394.90484ms","start":"2026-10-13T13:45:31.27054Z","end":"2026-10-13T13:45:31.665445Z","steps":["trace[1770444191] 'range keys from in-memory index tree' (duration: 394.760976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.665453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.964147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:31.665548Z","caller":"traceutil/trace.go:171","msg":"trace[790804551] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"591.378714ms","start":"2026-10-13T13:45:31.074147Z","end":"2026-10-13T13:45:31.665525Z","steps":["trace[790804551] 'process raft request' (duration: 249.260743ms)","trace[790804551] 'compare' (duration: 341.675759ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:31.665607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.074124Z","time spent":"591.454106ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:31.668917Z","caller":"traceutil/trace.go:171","msg":"trace[91418176] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"593.990506ms","start":"2026-10-13T13:45:31.0749Z","end":"2026-10-13T13:45:31.66889Z","steps":["trace[91418176] 'process raft request' (duration: 593.78227ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:31.669095Z","caller":"traceutil/trace.go:171","msg":"trace[1297744348] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"592.171226ms","start":"2026-10-13T13:45:31.076907Z","end":"2026-10-13T13:45:31.669079Z","steps":["trace[1297744348] 'process raft request' (duration: 591.933679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.669107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.074882Z","time spent":"594.133119ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:31.669184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.076884Z","time spent":"592.255538ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:31.669183Z","caller":"traceutil/trace.go:171","msg":"trace[200266914] linearizableReadLoop","detail":"{readStateIndex:26967; appliedIndex:26965; }","duration":"593.917323ms","start":"2026-10-13T13:45:31.075246Z","end":"2026-10-13T13:45:31.669164Z","steps":["trace[200266914] 'read index received' (duration: 248.172282ms)","trace[200266914] 'applied index is now lower than readState.Index' (duration: 345.740321ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:31.669232Z","caller":"traceutil/trace.go:171","msg":"trace[1495649827] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"497.158357ms","start":"2026-10-13T13:45:31.172045Z","end":"2026-10-13T13:45:31.669204Z","steps":["trace[1495649827] 'process raft request' (duration: 496.965752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.669337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.172007Z","time spent":"497.26683ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:31.669354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.074518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:31.669431Z","caller":"traceutil/trace.go:171","msg":"trace[2057180911] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26109; }","duration":"594.194301ms","start":"2026-10-13T13:45:31.075217Z","end":"2026-10-13T13:45:31.669412Z","steps":["trace[2057180911] 'agreement among raft nodes before linearized reading' (duration: 594.028767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.669488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.075206Z","time spent":"594.271114ms","remote":"127.0.0.1:37906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-13T13:45:31.693194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.67781ms","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-13T13:45:31.693274Z","caller":"traceutil/trace.go:171","msg":"trace[707029248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26112; }","duration":"458.773473ms","start":"2026-10-13T13:45:31.234481Z","end":"2026-10-13T13:45:31.693255Z","steps":["trace[707029248] 'agreement among raft nodes before linearized reading' (duration: 458.622049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.69327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.425632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:31.693323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.234446Z","time spent":"458.861845ms","remote":"127.0.0.1:40666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"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-13T13:45:31.69333Z","caller":"traceutil/trace.go:171","msg":"trace[598086593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26112; }","duration":"486.528904ms","start":"2026-10-13T13:45:31.206784Z","end":"2026-10-13T13:45:31.693313Z","steps":["trace[598086593] 'agreement among raft nodes before linearized reading' (duration: 486.415821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.69319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.208628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-10-13T13:45:31.693371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.206753Z","time spent":"486.610307ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T13:45:31.693409Z","caller":"traceutil/trace.go:171","msg":"trace[410523622] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26112; }","duration":"615.482044ms","start":"2026-10-13T13:45:31.077906Z","end":"2026-10-13T13:45:31.693388Z","steps":["trace[410523622] 'agreement among raft nodes before linearized reading' (duration: 615.076803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:31.693479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:31.077896Z","time spent":"615.564967ms","remote":"127.0.0.1:37830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-13T13:45:32.370303Z","caller":"traceutil/trace.go:171","msg":"trace[101289372] linearizableReadLoop","detail":"{readStateIndex:26974; appliedIndex:26973; }","duration":"260.589674ms","start":"2026-10-13T13:45:32.109691Z","end":"2026-10-13T13:45:32.370281Z","steps":["trace[101289372] 'read index received' (duration: 260.397169ms)","trace[101289372] 'applied index is now lower than readState.Index' (duration: 191.465µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:32.370405Z","caller":"traceutil/trace.go:171","msg":"trace[1980682970] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"354.778059ms","start":"2026-10-13T13:45:32.015592Z","end":"2026-10-13T13:45:32.37037Z","steps":["trace[1980682970] 'process raft request' (duration: 354.534193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:32.370481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.567231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:32.37046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.741768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:32.370521Z","caller":"traceutil/trace.go:171","msg":"trace[1366058125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26114; }","duration":"159.662603ms","start":"2026-10-13T13:45:32.210848Z","end":"2026-10-13T13:45:32.370511Z","steps":["trace[1366058125] 'agreement among raft nodes before linearized reading' (duration: 159.573671ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:32.370542Z","caller":"traceutil/trace.go:171","msg":"trace[264105379] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:26114; }","duration":"260.869941ms","start":"2026-10-13T13:45:32.10965Z","end":"2026-10-13T13:45:32.37052Z","steps":["trace[264105379] 'agreement among raft nodes before linearized reading' (duration: 260.719377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:32.370549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.047973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:32.370574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:32.015573Z","time spent":"354.913554ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:32.370612Z","caller":"traceutil/trace.go:171","msg":"trace[582778604] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:26114; }","duration":"133.116085ms","start":"2026-10-13T13:45:32.23748Z","end":"2026-10-13T13:45:32.370596Z","steps":["trace[582778604] 'agreement among raft nodes before linearized reading' (duration: 133.024342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:32.642439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.734655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:32.64252Z","caller":"traceutil/trace.go:171","msg":"trace[1838518808] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26114; }","duration":"194.881229ms","start":"2026-10-13T13:45:32.447622Z","end":"2026-10-13T13:45:32.642503Z","steps":["trace[1838518808] 'count revisions from in-memory index tree' (duration: 194.639173ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:33.071153Z","caller":"traceutil/trace.go:171","msg":"trace[1972297677] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"146.229544ms","start":"2026-10-13T13:45:32.924882Z","end":"2026-10-13T13:45:33.071112Z","steps":["trace[1972297677] 'process raft request' (duration: 131.283293ms)","trace[1972297677] 'compare' (duration: 14.814067ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:34.131189Z","caller":"traceutil/trace.go:171","msg":"trace[603893158] linearizableReadLoop","detail":"{readStateIndex:26980; appliedIndex:26979; }","duration":"457.309443ms","start":"2026-10-13T13:45:33.673854Z","end":"2026-10-13T13:45:34.131163Z","steps":["trace[603893158] 'read index received' (duration: 457.17206ms)","trace[603893158] 'applied index is now lower than readState.Index' (duration: 136.193µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:34.131251Z","caller":"traceutil/trace.go:171","msg":"trace[988892302] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"457.662783ms","start":"2026-10-13T13:45:33.673553Z","end":"2026-10-13T13:45:34.131216Z","steps":["trace[988892302] 'process raft request' (duration: 457.402586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.131422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.54495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-13T13:45:34.131422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.673523Z","time spent":"457.811067ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:34.131461Z","caller":"traceutil/trace.go:171","msg":"trace[1663540301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26120; }","duration":"457.658003ms","start":"2026-10-13T13:45:33.673792Z","end":"2026-10-13T13:45:34.13145Z","steps":["trace[1663540301] 'agreement among raft nodes before linearized reading' (duration: 457.489859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.131496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.673759Z","time spent":"457.728795ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-13T13:45:34.345429Z","caller":"traceutil/trace.go:171","msg":"trace[94218959] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"670.529517ms","start":"2026-10-13T13:45:33.674876Z","end":"2026-10-13T13:45:34.345406Z","steps":["trace[94218959] 'process raft request' (duration: 662.658271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.345567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.674862Z","time spent":"670.631409ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:34.34577Z","caller":"traceutil/trace.go:171","msg":"trace[2117451929] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"209.920282ms","start":"2026-10-13T13:45:34.135825Z","end":"2026-10-13T13:45:34.345746Z","steps":["trace[2117451929] 'process raft request' (duration: 209.883141ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:34.345857Z","caller":"traceutil/trace.go:171","msg":"trace[1604431812] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"647.445823ms","start":"2026-10-13T13:45:33.6984Z","end":"2026-10-13T13:45:34.345846Z","steps":["trace[1604431812] 'process raft request' (duration: 647.118854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.345927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.698382Z","time spent":"647.501135ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:34.345928Z","caller":"traceutil/trace.go:171","msg":"trace[381167928] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"647.373401ms","start":"2026-10-13T13:45:33.698544Z","end":"2026-10-13T13:45:34.345918Z","steps":["trace[381167928] 'process raft request' (duration: 647.085003ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:34.345893Z","caller":"traceutil/trace.go:171","msg":"trace[654092279] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"646.072115ms","start":"2026-10-13T13:45:33.699787Z","end":"2026-10-13T13:45:34.345859Z","steps":["trace[654092279] 'process raft request' (duration: 645.87802ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:34.345902Z","caller":"traceutil/trace.go:171","msg":"trace[2009730474] linearizableReadLoop","detail":"{readStateIndex:26985; appliedIndex:26980; }","duration":"214.609651ms","start":"2026-10-13T13:45:34.131252Z","end":"2026-10-13T13:45:34.345862Z","steps":["trace[2009730474] 'read index received' (duration: 206.380835ms)","trace[2009730474] 'applied index is now lower than readState.Index' (duration: 8.223086ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:34.346055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.698528Z","time spent":"647.427543ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:34.34611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.699775Z","time spent":"646.201439ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:34.346141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.953094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:34.34618Z","caller":"traceutil/trace.go:171","msg":"trace[637838524] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26126; }","duration":"253.058487ms","start":"2026-10-13T13:45:34.093107Z","end":"2026-10-13T13:45:34.346165Z","steps":["trace[637838524] 'agreement among raft nodes before linearized reading' (duration: 252.947644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.34626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.252444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:34.346296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.64739ms","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-13T13:45:34.346282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.714929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:34.346337Z","caller":"traceutil/trace.go:171","msg":"trace[248126187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26126; }","duration":"305.723482ms","start":"2026-10-13T13:45:34.040603Z","end":"2026-10-13T13:45:34.346326Z","steps":["trace[248126187] 'agreement among raft nodes before linearized reading' (duration: 305.593649ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:34.346337Z","caller":"traceutil/trace.go:171","msg":"trace[375222454] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:26126; }","duration":"425.388328ms","start":"2026-10-13T13:45:33.920933Z","end":"2026-10-13T13:45:34.346321Z","steps":["trace[375222454] 'agreement among raft nodes before linearized reading' (duration: 425.258425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.346335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.991188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:34.346358Z","caller":"traceutil/trace.go:171","msg":"trace[1196468127] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26126; }","duration":"267.842903ms","start":"2026-10-13T13:45:34.078497Z","end":"2026-10-13T13:45:34.346339Z","steps":["trace[1196468127] 'agreement among raft nodes before linearized reading' (duration: 267.697389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.346365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:34.040582Z","time spent":"305.777914ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T13:45:34.34638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.920908Z","time spent":"425.460289ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-13T13:45:34.346388Z","caller":"traceutil/trace.go:171","msg":"trace[2032879489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26126; }","duration":"142.0793ms","start":"2026-10-13T13:45:34.204293Z","end":"2026-10-13T13:45:34.346372Z","steps":["trace[2032879489] 'agreement among raft nodes before linearized reading' (duration: 141.987418ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:34.345901Z","caller":"traceutil/trace.go:171","msg":"trace[366837535] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"667.72156ms","start":"2026-10-13T13:45:33.678143Z","end":"2026-10-13T13:45:34.345864Z","steps":["trace[366837535] 'process raft request' (duration: 667.212456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:34.346489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:33.678126Z","time spent":"668.332917ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:40.38808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.157457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T13:45:40.388185Z","caller":"traceutil/trace.go:171","msg":"trace[634058079] linearizableReadLoop","detail":"{readStateIndex:27026; appliedIndex:27025; }","duration":"229.282014ms","start":"2026-10-13T13:45:40.158886Z","end":"2026-10-13T13:45:40.388168Z","steps":["trace[634058079] 'read index received' (duration: 38.051µs)","trace[634058079] 'applied index is now lower than readState.Index' (duration: 229.242453ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:40.388722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.795518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-13T13:45:40.388813Z","caller":"traceutil/trace.go:171","msg":"trace[686617971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26164; }","duration":"229.940842ms","start":"2026-10-13T13:45:40.158846Z","end":"2026-10-13T13:45:40.388787Z","steps":["trace[686617971] 'agreement among raft nodes before linearized reading' (duration: 229.678395ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:40.733045Z","caller":"traceutil/trace.go:171","msg":"trace[247255931] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"559.905379ms","start":"2026-10-13T13:45:40.173113Z","end":"2026-10-13T13:45:40.733019Z","steps":["trace[247255931] 'process raft request' (duration: 559.525249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:40.733191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.173078Z","time spent":"560.034523ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419267,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:41.449635Z","caller":"traceutil/trace.go:171","msg":"trace[959401001] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"1.081138207s","start":"2026-10-13T13:45:40.368452Z","end":"2026-10-13T13:45:41.44959Z","steps":["trace[959401001] 'process raft request' (duration: 1.080785427s)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:41.449628Z","caller":"traceutil/trace.go:171","msg":"trace[1037097621] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"1.083592555s","start":"2026-10-13T13:45:40.365926Z","end":"2026-10-13T13:45:41.449518Z","steps":["trace[1037097621] 'process raft request' (duration: 1.083085841s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.449821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.368436Z","time spent":"1.081286681s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.449824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.365901Z","time spent":"1.083831351s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:41.449382Z","caller":"traceutil/trace.go:171","msg":"trace[2134490922] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"1.079547913s","start":"2026-10-13T13:45:40.36981Z","end":"2026-10-13T13:45:41.449358Z","steps":["trace[2134490922] 'process raft request' (duration: 1.079475251s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.450136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.369798Z","time spent":"1.080284773s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:41.450198Z","caller":"traceutil/trace.go:171","msg":"trace[188851204] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"1.079368108s","start":"2026-10-13T13:45:40.370808Z","end":"2026-10-13T13:45:41.450176Z","steps":["trace[188851204] 'process raft request' (duration: 1.078512064s)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:41.450324Z","caller":"traceutil/trace.go:171","msg":"trace[1755965605] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"1.057442656s","start":"2026-10-13T13:45:40.392854Z","end":"2026-10-13T13:45:41.450296Z","steps":["trace[1755965605] 'process raft request' (duration: 1.057270211s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.450319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.370797Z","time spent":"1.079451101s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:41.450483Z","caller":"traceutil/trace.go:171","msg":"trace[1226240291] linearizableReadLoop","detail":"{readStateIndex:27031; appliedIndex:27026; }","duration":"1.062233498s","start":"2026-10-13T13:45:40.388236Z","end":"2026-10-13T13:45:41.45047Z","steps":["trace[1226240291] 'read index received' (duration: 344.422524ms)","trace[1226240291] 'applied index is now lower than readState.Index' (duration: 717.810074ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:41.450482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.39283Z","time spent":"1.05756018s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:41.450539Z","caller":"traceutil/trace.go:171","msg":"trace[1270577766] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"1.053281303s","start":"2026-10-13T13:45:40.397251Z","end":"2026-10-13T13:45:41.450532Z","steps":["trace[1270577766] 'process raft request' (duration: 1.052996905s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.450599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.397219Z","time spent":"1.053348294s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.450642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080503319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-13T13:45:41.450642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065318612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T13:45:41.450507Z","caller":"traceutil/trace.go:171","msg":"trace[6228941] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"873.447016ms","start":"2026-10-13T13:45:40.577044Z","end":"2026-10-13T13:45:41.450491Z","steps":["trace[6228941] 'process raft request' (duration: 873.343443ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:41.450682Z","caller":"traceutil/trace.go:171","msg":"trace[1123504428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26172; }","duration":"1.080571381s","start":"2026-10-13T13:45:40.370098Z","end":"2026-10-13T13:45:41.450669Z","steps":["trace[1123504428] 'agreement among raft nodes before linearized reading' (duration: 1.080445138s)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:41.450694Z","caller":"traceutil/trace.go:171","msg":"trace[1664597173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26172; }","duration":"1.065407666s","start":"2026-10-13T13:45:40.385271Z","end":"2026-10-13T13:45:41.450679Z","steps":["trace[1664597173] 'agreement among raft nodes before linearized reading' (duration: 1.0652165s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.450711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.370087Z","time spent":"1.080616032s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T13:45:41.450724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.385249Z","time spent":"1.065468117s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-13T13:45:41.45072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.57702Z","time spent":"873.677122ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.450828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246002602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:41.450926Z","caller":"traceutil/trace.go:171","msg":"trace[699005566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26172; }","duration":"1.246141347s","start":"2026-10-13T13:45:40.204758Z","end":"2026-10-13T13:45:41.4509Z","steps":["trace[699005566] 'agreement among raft nodes before linearized reading' (duration: 1.245999093s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.45098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.204738Z","time spent":"1.246229179s","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T13:45:41.500637Z","caller":"traceutil/trace.go:171","msg":"trace[789157975] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"670.3111ms","start":"2026-10-13T13:45:40.830298Z","end":"2026-10-13T13:45:41.500609Z","steps":["trace[789157975] 'process raft request' (duration: 660.0912ms)","trace[789157975] 'compare' (duration: 10.062986ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:41.500923Z","caller":"traceutil/trace.go:171","msg":"trace[1279101751] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"378.104468ms","start":"2026-10-13T13:45:41.122801Z","end":"2026-10-13T13:45:41.500905Z","steps":["trace[1279101751] 'process raft request' (duration: 378.044227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.501038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.83027Z","time spent":"670.436523ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.501125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.37719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:45:41.50118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.660757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:41.50121Z","caller":"traceutil/trace.go:171","msg":"trace[2018406987] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"389.11255ms","start":"2026-10-13T13:45:41.112048Z","end":"2026-10-13T13:45:41.501161Z","steps":["trace[2018406987] 'process raft request' (duration: 388.681829ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:41.50123Z","caller":"traceutil/trace.go:171","msg":"trace[1652444850] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26176; }","duration":"843.532915ms","start":"2026-10-13T13:45:40.657669Z","end":"2026-10-13T13:45:41.501202Z","steps":["trace[1652444850] 'agreement among raft nodes before linearized reading' (duration: 843.311618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.501256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.030572ms","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-13T13:45:41.501273Z","caller":"traceutil/trace.go:171","msg":"trace[412533915] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26176; }","duration":"561.840332ms","start":"2026-10-13T13:45:40.939414Z","end":"2026-10-13T13:45:41.501254Z","steps":["trace[412533915] 'agreement among raft nodes before linearized reading' (duration: 561.556344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.501038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:41.122775Z","time spent":"378.189741ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.501271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106709289s","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-13T13:45:41.501299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.65764Z","time spent":"843.642728ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-10-13T13:45:41.501305Z","caller":"traceutil/trace.go:171","msg":"trace[1418260072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26176; }","duration":"376.105784ms","start":"2026-10-13T13:45:41.12518Z","end":"2026-10-13T13:45:41.501286Z","steps":["trace[1418260072] 'agreement among raft nodes before linearized reading' (duration: 376.006851ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:41.501335Z","caller":"traceutil/trace.go:171","msg":"trace[1229083083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26176; }","duration":"1.106812302s","start":"2026-10-13T13:45:40.394507Z","end":"2026-10-13T13:45:41.50132Z","steps":["trace[1229083083] 'agreement among raft nodes before linearized reading' (duration: 1.106596516s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.501326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.939384Z","time spent":"561.924924ms","remote":"127.0.0.1:40616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-13T13:45:41.501106Z","caller":"traceutil/trace.go:171","msg":"trace[682644891] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"608.37831ms","start":"2026-10-13T13:45:40.892717Z","end":"2026-10-13T13:45:41.501095Z","steps":["trace[682644891] 'process raft request' (duration: 607.848975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:41.501355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:41.125163Z","time spent":"376.181425ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-13T13:45:41.501374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.394495Z","time spent":"1.106871173s","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T13:45:41.501375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:41.112023Z","time spent":"389.250124ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.501428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:40.8927Z","time spent":"608.673568ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:41.501122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.542691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19107"} {"level":"info","ts":"2026-10-13T13:45:41.501491Z","caller":"traceutil/trace.go:171","msg":"trace[1488540710] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26176; }","duration":"119.983303ms","start":"2026-10-13T13:45:41.381499Z","end":"2026-10-13T13:45:41.501482Z","steps":["trace[1488540710] 'agreement among raft nodes before linearized reading' (duration: 119.422138ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.084177Z","caller":"traceutil/trace.go:171","msg":"trace[1224609418] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"127.610743ms","start":"2026-10-13T13:45:48.956542Z","end":"2026-10-13T13:45:49.084153Z","steps":["trace[1224609418] 'process raft request' (duration: 127.352856ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.640332Z","caller":"traceutil/trace.go:171","msg":"trace[1953706364] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"151.639622ms","start":"2026-10-13T13:45:49.488669Z","end":"2026-10-13T13:45:49.640308Z","steps":["trace[1953706364] 'process raft request' (duration: 151.405176ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.640593Z","caller":"traceutil/trace.go:171","msg":"trace[1718804056] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"151.790587ms","start":"2026-10-13T13:45:49.488791Z","end":"2026-10-13T13:45:49.640582Z","steps":["trace[1718804056] 'process raft request' (duration: 151.472858ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.640693Z","caller":"traceutil/trace.go:171","msg":"trace[719703878] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"151.833038ms","start":"2026-10-13T13:45:49.488835Z","end":"2026-10-13T13:45:49.640669Z","steps":["trace[719703878] 'process raft request' (duration: 151.676454ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.640859Z","caller":"traceutil/trace.go:171","msg":"trace[1681519764] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"128.033804ms","start":"2026-10-13T13:45:49.51279Z","end":"2026-10-13T13:45:49.640823Z","steps":["trace[1681519764] 'process raft request' (duration: 127.838399ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.641035Z","caller":"traceutil/trace.go:171","msg":"trace[2147017221] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"112.521559ms","start":"2026-10-13T13:45:49.528456Z","end":"2026-10-13T13:45:49.640977Z","steps":["trace[2147017221] 'process raft request' (duration: 112.304623ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.641071Z","caller":"traceutil/trace.go:171","msg":"trace[1102354982] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"109.088634ms","start":"2026-10-13T13:45:49.531971Z","end":"2026-10-13T13:45:49.64106Z","steps":["trace[1102354982] 'process raft request' (duration: 108.921419ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.641074Z","caller":"traceutil/trace.go:171","msg":"trace[630195326] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"102.097233ms","start":"2026-10-13T13:45:49.53896Z","end":"2026-10-13T13:45:49.641057Z","steps":["trace[630195326] 'process raft request' (duration: 101.98582ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.641051Z","caller":"traceutil/trace.go:171","msg":"trace[1914838410] linearizableReadLoop","detail":"{readStateIndex:27148; appliedIndex:27144; }","duration":"146.985855ms","start":"2026-10-13T13:45:49.494045Z","end":"2026-10-13T13:45:49.641031Z","steps":["trace[1914838410] 'read index received' (duration: 47.377201ms)","trace[1914838410] 'applied index is now lower than readState.Index' (duration: 99.607084ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:49.641229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.16112ms","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-13T13:45:49.641283Z","caller":"traceutil/trace.go:171","msg":"trace[609719663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26289; }","duration":"147.277223ms","start":"2026-10-13T13:45:49.493992Z","end":"2026-10-13T13:45:49.641269Z","steps":["trace[609719663] 'agreement among raft nodes before linearized reading' (duration: 147.094848ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:49.925304Z","caller":"traceutil/trace.go:171","msg":"trace[1862241156] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"278.588076ms","start":"2026-10-13T13:45:49.646691Z","end":"2026-10-13T13:45:49.925279Z","steps":["trace[1862241156] 'process raft request' (duration: 278.412362ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:50.077166Z","caller":"traceutil/trace.go:171","msg":"trace[1621002762] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"430.219669ms","start":"2026-10-13T13:45:49.646918Z","end":"2026-10-13T13:45:50.077138Z","steps":["trace[1621002762] 'process raft request' (duration: 406.888679ms)","trace[1621002762] 'compare' (duration: 23.091313ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:50.077326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:49.646891Z","time spent":"430.343742ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:50.077837Z","caller":"traceutil/trace.go:171","msg":"trace[1322846791] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"430.780794ms","start":"2026-10-13T13:45:49.647044Z","end":"2026-10-13T13:45:50.077825Z","steps":["trace[1322846791] 'process raft request' (duration: 430.034593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:50.077904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:49.647029Z","time spent":"430.840416ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:50.078315Z","caller":"traceutil/trace.go:171","msg":"trace[2075389742] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"428.131151ms","start":"2026-10-13T13:45:49.650143Z","end":"2026-10-13T13:45:50.078274Z","steps":["trace[2075389742] 'process raft request' (duration: 428.054179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:50.078386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:49.650115Z","time spent":"428.235434ms","remote":"127.0.0.1:37830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:45:50.078557Z","caller":"traceutil/trace.go:171","msg":"trace[526028937] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"430.555547ms","start":"2026-10-13T13:45:49.647979Z","end":"2026-10-13T13:45:50.078535Z","steps":["trace[526028937] 'process raft request' (duration: 430.172247ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:50.078649Z","caller":"traceutil/trace.go:171","msg":"trace[1621452213] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"431.576786ms","start":"2026-10-13T13:45:49.64704Z","end":"2026-10-13T13:45:50.078617Z","steps":["trace[1621452213] 'process raft request' (duration: 430.718832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:50.078769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:49.647966Z","time spent":"430.735603ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:50.078791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:45:49.647026Z","time spent":"431.687049ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:45:50.253975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.703703ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T13:45:50.25424Z","caller":"traceutil/trace.go:171","msg":"trace[527998816] linearizableReadLoop","detail":"{readStateIndex:27159; appliedIndex:27153; }","duration":"276.834909ms","start":"2026-10-13T13:45:49.977385Z","end":"2026-10-13T13:45:50.25422Z","steps":["trace[527998816] 'read index received' (duration: 76.49559ms)","trace[527998816] 'applied index is now lower than readState.Index' (duration: 200.337989ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:45:50.254455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.055745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:45:50.254526Z","caller":"traceutil/trace.go:171","msg":"trace[2004236872] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26295; }","duration":"277.140858ms","start":"2026-10-13T13:45:49.977368Z","end":"2026-10-13T13:45:50.254509Z","steps":["trace[2004236872] 'agreement among raft nodes before linearized reading' (duration: 277.013234ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:50.254687Z","caller":"traceutil/trace.go:171","msg":"trace[1180294735] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"224.727809ms","start":"2026-10-13T13:45:50.029933Z","end":"2026-10-13T13:45:50.254661Z","steps":["trace[1180294735] 'process raft request' (duration: 224.184874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:50.25771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.502349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-13T13:45:50.258408Z","caller":"traceutil/trace.go:171","msg":"trace[603388575] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26296; }","duration":"238.505717ms","start":"2026-10-13T13:45:50.01978Z","end":"2026-10-13T13:45:50.258286Z","steps":["trace[603388575] 'agreement among raft nodes before linearized reading' (duration: 234.850036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:51.930507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.134113ms","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-13T13:45:51.930597Z","caller":"traceutil/trace.go:171","msg":"trace[1857850872] 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:26308; }","duration":"220.279736ms","start":"2026-10-13T13:45:51.710298Z","end":"2026-10-13T13:45:51.930578Z","steps":["trace[1857850872] 'range keys from in-memory index tree' (duration: 220.06298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:57.923623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.735668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:45:57.923745Z","caller":"traceutil/trace.go:171","msg":"trace[270862667] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"253.399246ms","start":"2026-10-13T13:45:57.670317Z","end":"2026-10-13T13:45:57.923716Z","steps":["trace[270862667] 'process raft request' (duration: 106.461003ms)","trace[270862667] 'compare' (duration: 146.567163ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:57.945894Z","caller":"traceutil/trace.go:171","msg":"trace[838206808] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"275.071291ms","start":"2026-10-13T13:45:57.670796Z","end":"2026-10-13T13:45:57.945867Z","steps":["trace[838206808] 'process raft request' (duration: 274.886686ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:57.946201Z","caller":"traceutil/trace.go:171","msg":"trace[1381739167] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"274.906396ms","start":"2026-10-13T13:45:57.671256Z","end":"2026-10-13T13:45:57.946162Z","steps":["trace[1381739167] 'process raft request' (duration: 274.565467ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:57.946303Z","caller":"traceutil/trace.go:171","msg":"trace[1006206199] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"269.631861ms","start":"2026-10-13T13:45:57.676654Z","end":"2026-10-13T13:45:57.946286Z","steps":["trace[1006206199] 'process raft request' (duration: 269.524728ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:57.946308Z","caller":"traceutil/trace.go:171","msg":"trace[1422219241] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"269.757385ms","start":"2026-10-13T13:45:57.676535Z","end":"2026-10-13T13:45:57.946293Z","steps":["trace[1422219241] 'process raft request' (duration: 269.519418ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:57.946343Z","caller":"traceutil/trace.go:171","msg":"trace[1300287277] linearizableReadLoop","detail":"{readStateIndex:27241; appliedIndex:27235; }","duration":"157.911595ms","start":"2026-10-13T13:45:57.788406Z","end":"2026-10-13T13:45:57.946317Z","steps":["trace[1300287277] 'read index received' (duration: 157.232797ms)","trace[1300287277] 'applied index is now lower than readState.Index' (duration: 677.648µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:45:57.946404Z","caller":"traceutil/trace.go:171","msg":"trace[2032256895] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"268.213632ms","start":"2026-10-13T13:45:57.67818Z","end":"2026-10-13T13:45:57.946394Z","steps":["trace[2032256895] 'process raft request' (duration: 268.087569ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:57.946419Z","caller":"traceutil/trace.go:171","msg":"trace[696444351] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"269.268601ms","start":"2026-10-13T13:45:57.677125Z","end":"2026-10-13T13:45:57.946394Z","steps":["trace[696444351] 'process raft request' (duration: 269.104197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:57.946566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.146461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-13T13:45:57.946609Z","caller":"traceutil/trace.go:171","msg":"trace[1285708021] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26375; }","duration":"158.241124ms","start":"2026-10-13T13:45:57.788357Z","end":"2026-10-13T13:45:57.946598Z","steps":["trace[1285708021] 'agreement among raft nodes before linearized reading' (duration: 158.064389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:58.301241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.009937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-13T13:45:58.301328Z","caller":"traceutil/trace.go:171","msg":"trace[843378765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26375; }","duration":"268.150361ms","start":"2026-10-13T13:45:58.033161Z","end":"2026-10-13T13:45:58.301311Z","steps":["trace[843378765] 'range keys from in-memory index tree' (duration: 267.855453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:58.301589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.779407ms","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-13T13:45:58.301667Z","caller":"traceutil/trace.go:171","msg":"trace[283865216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26375; }","duration":"182.900471ms","start":"2026-10-13T13:45:58.118744Z","end":"2026-10-13T13:45:58.301645Z","steps":["trace[283865216] 'range keys from in-memory index tree' (duration: 182.604352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:58.301792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.660643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-13T13:45:58.301819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.285266ms","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-13T13:45:58.301831Z","caller":"traceutil/trace.go:171","msg":"trace[1033226030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26375; }","duration":"182.717166ms","start":"2026-10-13T13:45:58.119102Z","end":"2026-10-13T13:45:58.30182Z","steps":["trace[1033226030] 'range keys from in-memory index tree' (duration: 182.549591ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:45:58.30186Z","caller":"traceutil/trace.go:171","msg":"trace[1244911586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26375; }","duration":"177.340368ms","start":"2026-10-13T13:45:58.124506Z","end":"2026-10-13T13:45:58.301846Z","steps":["trace[1244911586] 'range keys from in-memory index tree' (duration: 177.198694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:45:58.301922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.165538ms","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-13T13:45:58.302026Z","caller":"traceutil/trace.go:171","msg":"trace[2125884381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26375; }","duration":"183.303201ms","start":"2026-10-13T13:45:58.118686Z","end":"2026-10-13T13:45:58.301989Z","steps":["trace[2125884381] 'range keys from in-memory index tree' (duration: 182.89805ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:04.164347Z","caller":"traceutil/trace.go:171","msg":"trace[1807387774] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"195.301351ms","start":"2026-10-13T13:46:03.969Z","end":"2026-10-13T13:46:04.164301Z","steps":["trace[1807387774] 'process raft request' (duration: 104.662413ms)","trace[1807387774] 'compare' (duration: 90.36682ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:04.315512Z","caller":"traceutil/trace.go:171","msg":"trace[1784443329] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"343.457827ms","start":"2026-10-13T13:46:03.972028Z","end":"2026-10-13T13:46:04.315486Z","steps":["trace[1784443329] 'process raft request' (duration: 307.822399ms)","trace[1784443329] 'compare' (duration: 35.469203ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:46:04.315649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:03.971994Z","time spent":"343.5835ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:04.315834Z","caller":"traceutil/trace.go:171","msg":"trace[1312339036] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"339.299293ms","start":"2026-10-13T13:46:03.976518Z","end":"2026-10-13T13:46:04.315817Z","steps":["trace[1312339036] 'process raft request' (duration: 338.922892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:04.315906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:03.976494Z","time spent":"339.376825ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:04.31595Z","caller":"traceutil/trace.go:171","msg":"trace[358486719] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"338.814019ms","start":"2026-10-13T13:46:03.977102Z","end":"2026-10-13T13:46:04.315916Z","steps":["trace[358486719] 'process raft request' (duration: 338.712637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:04.316174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:03.977086Z","time spent":"338.985844ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:04.316232Z","caller":"traceutil/trace.go:171","msg":"trace[869883108] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"339.020905ms","start":"2026-10-13T13:46:03.977165Z","end":"2026-10-13T13:46:04.316185Z","steps":["trace[869883108] 'process raft request' (duration: 338.704306ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:04.315998Z","caller":"traceutil/trace.go:171","msg":"trace[593522697] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"338.897391ms","start":"2026-10-13T13:46:03.977088Z","end":"2026-10-13T13:46:04.315985Z","steps":["trace[593522697] 'process raft request' (duration: 338.569482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:04.316379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:03.977142Z","time spent":"339.161249ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:04.316394Z","caller":"traceutil/trace.go:171","msg":"trace[707945192] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"220.540353ms","start":"2026-10-13T13:46:04.09584Z","end":"2026-10-13T13:46:04.31638Z","steps":["trace[707945192] 'process raft request' (duration: 220.260755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:04.317276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:03.977077Z","time spent":"339.287783ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:20.233368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.544531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T13:46:20.370695Z","caller":"traceutil/trace.go:171","msg":"trace[1365818482] transaction","detail":"{read_only:false; response_revision:26551; number_of_response:1; }","duration":"253.328764ms","start":"2026-10-13T13:46:20.117339Z","end":"2026-10-13T13:46:20.370668Z","steps":["trace[1365818482] 'process raft request' (duration: 253.147339ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.371074Z","caller":"traceutil/trace.go:171","msg":"trace[1182480195] linearizableReadLoop","detail":"{readStateIndex:27423; appliedIndex:27423; }","duration":"165.210355ms","start":"2026-10-13T13:46:20.205832Z","end":"2026-10-13T13:46:20.371042Z","steps":["trace[1182480195] 'read index received' (duration: 165.176254ms)","trace[1182480195] 'applied index is now lower than readState.Index' (duration: 31.581µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:46:20.371205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.359429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:46:20.371258Z","caller":"traceutil/trace.go:171","msg":"trace[589168765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26551; }","duration":"165.467382ms","start":"2026-10-13T13:46:20.205778Z","end":"2026-10-13T13:46:20.371245Z","steps":["trace[589168765] 'agreement among raft nodes before linearized reading' (duration: 165.366509ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.39609Z","caller":"traceutil/trace.go:171","msg":"trace[645961106] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"127.569891ms","start":"2026-10-13T13:46:20.268502Z","end":"2026-10-13T13:46:20.396072Z","steps":["trace[645961106] 'process raft request' (duration: 127.382246ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.705048Z","caller":"traceutil/trace.go:171","msg":"trace[94201539] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"332.093906ms","start":"2026-10-13T13:46:20.372904Z","end":"2026-10-13T13:46:20.704998Z","steps":["trace[94201539] 'process raft request' (duration: 332.008204ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.705075Z","caller":"traceutil/trace.go:171","msg":"trace[724152514] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"333.35023ms","start":"2026-10-13T13:46:20.371681Z","end":"2026-10-13T13:46:20.705031Z","steps":["trace[724152514] 'process raft request' (duration: 333.029541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.705242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.371658Z","time spent":"333.492194ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:20.705266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.372879Z","time spent":"332.25242ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:20.70542Z","caller":"traceutil/trace.go:171","msg":"trace[756088784] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"330.211094ms","start":"2026-10-13T13:46:20.375173Z","end":"2026-10-13T13:46:20.705385Z","steps":["trace[756088784] 'process raft request' (duration: 329.783172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.70558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.375159Z","time spent":"330.342428ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:20.705776Z","caller":"traceutil/trace.go:171","msg":"trace[1982438257] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"326.285046ms","start":"2026-10-13T13:46:20.379475Z","end":"2026-10-13T13:46:20.70576Z","steps":["trace[1982438257] 'process raft request' (duration: 325.793723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.705841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.379441Z","time spent":"326.369079ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:20.705813Z","caller":"traceutil/trace.go:171","msg":"trace[572842650] linearizableReadLoop","detail":"{readStateIndex:27426; appliedIndex:27425; }","duration":"333.543666ms","start":"2026-10-13T13:46:20.372238Z","end":"2026-10-13T13:46:20.705782Z","steps":["trace[572842650] 'read index received' (duration: 23.788593ms)","trace[572842650] 'applied index is now lower than readState.Index' (duration: 309.753683ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:20.705843Z","caller":"traceutil/trace.go:171","msg":"trace[1862890804] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"325.810744ms","start":"2026-10-13T13:46:20.380006Z","end":"2026-10-13T13:46:20.705817Z","steps":["trace[1862890804] 'process raft request' (duration: 325.648939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.705961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.749881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:46:20.706007Z","caller":"traceutil/trace.go:171","msg":"trace[1768359119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26558; }","duration":"333.820424ms","start":"2026-10-13T13:46:20.372175Z","end":"2026-10-13T13:46:20.705996Z","steps":["trace[1768359119] 'agreement among raft nodes before linearized reading' (duration: 333.726251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.706064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.372166Z","time spent":"333.891215ms","remote":"127.0.0.1:37672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T13:46:20.706104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.003759ms","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-13T13:46:20.706123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.010062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-13T13:46:20.706153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.168838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-13T13:46:20.706134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.562227ms","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-13T13:46:20.706189Z","caller":"traceutil/trace.go:171","msg":"trace[226654612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26558; }","duration":"274.101763ms","start":"2026-10-13T13:46:20.432069Z","end":"2026-10-13T13:46:20.706171Z","steps":["trace[226654612] 'agreement among raft nodes before linearized reading' (duration: 273.883047ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.706202Z","caller":"traceutil/trace.go:171","msg":"trace[1024822543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26558; }","duration":"230.24308ms","start":"2026-10-13T13:46:20.475946Z","end":"2026-10-13T13:46:20.706189Z","steps":["trace[1024822543] 'agreement among raft nodes before linearized reading' (duration: 230.090516ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.706202Z","caller":"traceutil/trace.go:171","msg":"trace[1805689587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26558; }","duration":"228.129682ms","start":"2026-10-13T13:46:20.478034Z","end":"2026-10-13T13:46:20.706164Z","steps":["trace[1805689587] 'agreement among raft nodes before linearized reading' (duration: 227.921887ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:20.706265Z","caller":"traceutil/trace.go:171","msg":"trace[611276849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26558; }","duration":"223.66981ms","start":"2026-10-13T13:46:20.482528Z","end":"2026-10-13T13:46:20.706198Z","steps":["trace[611276849] 'agreement among raft nodes before linearized reading' (duration: 223.480725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.706001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.379988Z","time spent":"325.919296ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:20.706158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.838238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T13:46:20.706671Z","caller":"traceutil/trace.go:171","msg":"trace[1591885054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26558; }","duration":"254.387443ms","start":"2026-10-13T13:46:20.452266Z","end":"2026-10-13T13:46:20.706653Z","steps":["trace[1591885054] 'agreement among raft nodes before linearized reading' (duration: 253.780396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:20.978769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.983468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T13:46:20.978894Z","caller":"traceutil/trace.go:171","msg":"trace[1003012281] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"267.877183ms","start":"2026-10-13T13:46:20.710992Z","end":"2026-10-13T13:46:20.978869Z","steps":["trace[1003012281] 'process raft request' (duration: 129.70725ms)","trace[1003012281] 'compare' (duration: 137.485654ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:21.038715Z","caller":"traceutil/trace.go:171","msg":"trace[518701143] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"327.052477ms","start":"2026-10-13T13:46:20.711601Z","end":"2026-10-13T13:46:21.038654Z","steps":["trace[518701143] 'process raft request' (duration: 326.909743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:21.038928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.711582Z","time spent":"327.220962ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:21.039431Z","caller":"traceutil/trace.go:171","msg":"trace[967519202] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"327.707315ms","start":"2026-10-13T13:46:20.711691Z","end":"2026-10-13T13:46:21.039399Z","steps":["trace[967519202] 'process raft request' (duration: 326.925984ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:21.039567Z","caller":"traceutil/trace.go:171","msg":"trace[736428983] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"327.773497ms","start":"2026-10-13T13:46:20.71176Z","end":"2026-10-13T13:46:21.039533Z","steps":["trace[736428983] 'process raft request' (duration: 327.238462ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:21.039614Z","caller":"traceutil/trace.go:171","msg":"trace[1371484547] transaction","detail":"{read_only:false; response_revision:26563; number_of_response:1; }","duration":"326.656647ms","start":"2026-10-13T13:46:20.712938Z","end":"2026-10-13T13:46:21.039595Z","steps":["trace[1371484547] 'process raft request' (duration: 326.211185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:21.039619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.711685Z","time spent":"327.840509ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:21.039696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.712917Z","time spent":"326.735698ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:21.039731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:20.711732Z","time spent":"327.907911ms","remote":"127.0.0.1:37900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:21.191907Z","caller":"traceutil/trace.go:171","msg":"trace[1015473667] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"102.047301ms","start":"2026-10-13T13:46:21.089834Z","end":"2026-10-13T13:46:21.191881Z","steps":["trace[1015473667] 'process raft request' (duration: 20.918524ms)","trace[1015473667] 'compare' (duration: 80.990403ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:33.276115Z","caller":"traceutil/trace.go:171","msg":"trace[2091519430] transaction","detail":"{read_only:false; response_revision:26646; number_of_response:1; }","duration":"177.78413ms","start":"2026-10-13T13:46:33.098301Z","end":"2026-10-13T13:46:33.276085Z","steps":["trace[2091519430] 'process raft request' (duration: 177.564134ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:33.321539Z","caller":"traceutil/trace.go:171","msg":"trace[202939866] transaction","detail":"{read_only:false; response_revision:26647; number_of_response:1; }","duration":"221.50855ms","start":"2026-10-13T13:46:33.100003Z","end":"2026-10-13T13:46:33.321512Z","steps":["trace[202939866] 'process raft request' (duration: 221.289724ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:33.321673Z","caller":"traceutil/trace.go:171","msg":"trace[1222276754] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27519; }","duration":"221.551541ms","start":"2026-10-13T13:46:33.100083Z","end":"2026-10-13T13:46:33.321635Z","steps":["trace[1222276754] 'read index received' (duration: 175.744484ms)","trace[1222276754] 'applied index is now lower than readState.Index' (duration: 45.804837ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:33.321699Z","caller":"traceutil/trace.go:171","msg":"trace[1946348211] transaction","detail":"{read_only:false; response_revision:26648; number_of_response:1; }","duration":"220.624926ms","start":"2026-10-13T13:46:33.101044Z","end":"2026-10-13T13:46:33.321669Z","steps":["trace[1946348211] 'process raft request' (duration: 220.418591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:33.321872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.183379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T13:46:33.321876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.301838ms","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-13T13:46:33.321926Z","caller":"traceutil/trace.go:171","msg":"trace[1316677707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26648; }","duration":"116.277212ms","start":"2026-10-13T13:46:33.205637Z","end":"2026-10-13T13:46:33.321914Z","steps":["trace[1316677707] 'agreement among raft nodes before linearized reading' (duration: 116.18678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:33.321929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.823099ms","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-13T13:46:33.321964Z","caller":"traceutil/trace.go:171","msg":"trace[1763039345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26648; }","duration":"221.907321ms","start":"2026-10-13T13:46:33.100044Z","end":"2026-10-13T13:46:33.321951Z","steps":["trace[1763039345] 'agreement among raft nodes before linearized reading' (duration: 221.738907ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:33.321994Z","caller":"traceutil/trace.go:171","msg":"trace[1248500575] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26648; }","duration":"188.463343ms","start":"2026-10-13T13:46:33.133513Z","end":"2026-10-13T13:46:33.321936Z","steps":["trace[1248500575] 'agreement among raft nodes before linearized reading' (duration: 188.302388ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:33.5048Z","caller":"traceutil/trace.go:171","msg":"trace[1654121648] transaction","detail":"{read_only:false; response_revision:26649; number_of_response:1; }","duration":"178.817919ms","start":"2026-10-13T13:46:33.325947Z","end":"2026-10-13T13:46:33.504765Z","steps":["trace[1654121648] 'process raft request' (duration: 98.601636ms)","trace[1654121648] 'compare' (duration: 79.963466ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T13:46:33.505054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.019599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-13T13:46:33.504793Z","caller":"traceutil/trace.go:171","msg":"trace[1400156815] linearizableReadLoop","detail":"{readStateIndex:27523; appliedIndex:27522; }","duration":"176.774153ms","start":"2026-10-13T13:46:33.327988Z","end":"2026-10-13T13:46:33.504762Z","steps":["trace[1400156815] 'read index received' (duration: 96.519549ms)","trace[1400156815] 'applied index is now lower than readState.Index' (duration: 80.252773ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:33.505128Z","caller":"traceutil/trace.go:171","msg":"trace[1523502377] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26649; }","duration":"177.140052ms","start":"2026-10-13T13:46:33.327974Z","end":"2026-10-13T13:46:33.505114Z","steps":["trace[1523502377] 'agreement among raft nodes before linearized reading' (duration: 176.875165ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:43.917856Z","caller":"traceutil/trace.go:171","msg":"trace[505560536] linearizableReadLoop","detail":"{readStateIndex:27604; appliedIndex:27603; }","duration":"147.239912ms","start":"2026-10-13T13:46:43.770592Z","end":"2026-10-13T13:46:43.917832Z","steps":["trace[505560536] 'read index received' (duration: 146.994165ms)","trace[505560536] 'applied index is now lower than readState.Index' (duration: 244.847µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:43.917906Z","caller":"traceutil/trace.go:171","msg":"trace[2118190418] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"147.730145ms","start":"2026-10-13T13:46:43.770144Z","end":"2026-10-13T13:46:43.917874Z","steps":["trace[2118190418] 'process raft request' (duration: 147.504909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:43.918093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.485238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19107"} {"level":"info","ts":"2026-10-13T13:46:43.918146Z","caller":"traceutil/trace.go:171","msg":"trace[1509546466] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26728; }","duration":"147.598092ms","start":"2026-10-13T13:46:43.770535Z","end":"2026-10-13T13:46:43.918133Z","steps":["trace[1509546466] 'agreement among raft nodes before linearized reading' (duration: 147.394346ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:44.050611Z","caller":"traceutil/trace.go:171","msg":"trace[368152461] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"126.468811ms","start":"2026-10-13T13:46:43.924105Z","end":"2026-10-13T13:46:44.050573Z","steps":["trace[368152461] 'process raft request' (duration: 50.954288ms)","trace[368152461] 'compare' (duration: 75.247826ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:44.076458Z","caller":"traceutil/trace.go:171","msg":"trace[1262500267] transaction","detail":"{read_only:false; response_revision:26730; number_of_response:1; }","duration":"143.768706ms","start":"2026-10-13T13:46:43.932667Z","end":"2026-10-13T13:46:44.076435Z","steps":["trace[1262500267] 'process raft request' (duration: 143.605592ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:44.076679Z","caller":"traceutil/trace.go:171","msg":"trace[659186054] linearizableReadLoop","detail":"{readStateIndex:27606; appliedIndex:27604; }","duration":"136.927399ms","start":"2026-10-13T13:46:43.939717Z","end":"2026-10-13T13:46:44.076644Z","steps":["trace[659186054] 'read index received' (duration: 35.352441ms)","trace[659186054] 'applied index is now lower than readState.Index' (duration: 101.572348ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:44.076789Z","caller":"traceutil/trace.go:171","msg":"trace[193528236] transaction","detail":"{read_only:false; response_revision:26731; number_of_response:1; }","duration":"135.925441ms","start":"2026-10-13T13:46:43.940839Z","end":"2026-10-13T13:46:44.076764Z","steps":["trace[193528236] 'process raft request' (duration: 135.541631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:44.076907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.175635ms","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-13T13:46:44.076955Z","caller":"traceutil/trace.go:171","msg":"trace[1470521175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26731; }","duration":"137.279408ms","start":"2026-10-13T13:46:43.939661Z","end":"2026-10-13T13:46:44.076941Z","steps":["trace[1470521175] 'agreement among raft nodes before linearized reading' (duration: 137.133594ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:44.621965Z","caller":"traceutil/trace.go:171","msg":"trace[511364030] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"141.816813ms","start":"2026-10-13T13:46:44.480094Z","end":"2026-10-13T13:46:44.621911Z","steps":["trace[511364030] 'process raft request' (duration: 102.047262ms)","trace[511364030] 'compare' (duration: 39.632237ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:44.862903Z","caller":"traceutil/trace.go:171","msg":"trace[504950868] transaction","detail":"{read_only:false; response_revision:26734; number_of_response:1; }","duration":"157.276547ms","start":"2026-10-13T13:46:44.705601Z","end":"2026-10-13T13:46:44.862878Z","steps":["trace[504950868] 'process raft request' (duration: 157.03263ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.01152Z","caller":"traceutil/trace.go:171","msg":"trace[1254498865] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"217.547732ms","start":"2026-10-13T13:46:44.793936Z","end":"2026-10-13T13:46:45.011483Z","steps":["trace[1254498865] 'process raft request' (duration: 187.575229ms)","trace[1254498865] 'compare' (duration: 29.779437ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:45.01157Z","caller":"traceutil/trace.go:171","msg":"trace[951474273] transaction","detail":"{read_only:false; response_revision:26737; number_of_response:1; }","duration":"212.453841ms","start":"2026-10-13T13:46:44.799092Z","end":"2026-10-13T13:46:45.011546Z","steps":["trace[951474273] 'process raft request' (duration: 212.369689ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.011735Z","caller":"traceutil/trace.go:171","msg":"trace[2049213087] transaction","detail":"{read_only:false; response_revision:26736; number_of_response:1; }","duration":"215.853944ms","start":"2026-10-13T13:46:44.795871Z","end":"2026-10-13T13:46:45.011725Z","steps":["trace[2049213087] 'process raft request' (duration: 215.525915ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.011734Z","caller":"traceutil/trace.go:171","msg":"trace[1833890114] linearizableReadLoop","detail":"{readStateIndex:27615; appliedIndex:27609; }","duration":"191.668211ms","start":"2026-10-13T13:46:44.820049Z","end":"2026-10-13T13:46:45.011718Z","steps":["trace[1833890114] 'read index received' (duration: 42.61569ms)","trace[1833890114] 'applied index is now lower than readState.Index' (duration: 149.051711ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:45.011821Z","caller":"traceutil/trace.go:171","msg":"trace[1121518094] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"211.66518ms","start":"2026-10-13T13:46:44.800143Z","end":"2026-10-13T13:46:45.011809Z","steps":["trace[1121518094] 'process raft request' (duration: 211.365931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:45.011906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.867967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.32\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-13T13:46:45.011946Z","caller":"traceutil/trace.go:171","msg":"trace[1693021015] range","detail":"{range_begin:/registry/masterleases/162.253.55.32; range_end:; response_count:1; response_revision:26739; }","duration":"191.960189ms","start":"2026-10-13T13:46:44.819975Z","end":"2026-10-13T13:46:45.011935Z","steps":["trace[1693021015] 'agreement among raft nodes before linearized reading' (duration: 191.802615ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.01207Z","caller":"traceutil/trace.go:171","msg":"trace[1774907936] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"211.32922ms","start":"2026-10-13T13:46:44.800688Z","end":"2026-10-13T13:46:45.012017Z","steps":["trace[1774907936] 'process raft request' (duration: 210.95009ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.176294Z","caller":"traceutil/trace.go:171","msg":"trace[250827877] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"307.268905ms","start":"2026-10-13T13:46:44.868984Z","end":"2026-10-13T13:46:45.176253Z","steps":["trace[250827877] 'process raft request' (duration: 271.366819ms)","trace[250827877] 'compare' (duration: 35.590777ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:45.176359Z","caller":"traceutil/trace.go:171","msg":"trace[29223550] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"304.706334ms","start":"2026-10-13T13:46:44.871626Z","end":"2026-10-13T13:46:45.176333Z","steps":["trace[29223550] 'process raft request' (duration: 304.502559ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.176642Z","caller":"traceutil/trace.go:171","msg":"trace[2141865926] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"304.554611ms","start":"2026-10-13T13:46:44.872049Z","end":"2026-10-13T13:46:45.176603Z","steps":["trace[2141865926] 'process raft request' (duration: 304.193671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:45.176472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:44.868958Z","time spent":"307.392588ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:45.176716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:44.871595Z","time spent":"304.984672ms","remote":"127.0.0.1:37906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1206,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T13:46:45.176841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T13:46:44.872017Z","time spent":"304.703814ms","remote":"127.0.0.1:58786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T13:46:45.357098Z","caller":"traceutil/trace.go:171","msg":"trace[1961268675] linearizableReadLoop","detail":"{readStateIndex:27622; appliedIndex:27619; }","duration":"152.28548ms","start":"2026-10-13T13:46:45.204793Z","end":"2026-10-13T13:46:45.357078Z","steps":["trace[1961268675] 'read index received' (duration: 27.983908ms)","trace[1961268675] 'applied index is now lower than readState.Index' (duration: 124.300752ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:46:45.357195Z","caller":"traceutil/trace.go:171","msg":"trace[2100089564] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"172.786853ms","start":"2026-10-13T13:46:45.184375Z","end":"2026-10-13T13:46:45.357162Z","steps":["trace[2100089564] 'process raft request' (duration: 172.627778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:45.357271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.467895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T13:46:45.357325Z","caller":"traceutil/trace.go:171","msg":"trace[903306961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26745; }","duration":"152.581079ms","start":"2026-10-13T13:46:45.204729Z","end":"2026-10-13T13:46:45.35731Z","steps":["trace[903306961] 'agreement among raft nodes before linearized reading' (duration: 152.480646ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.35731Z","caller":"traceutil/trace.go:171","msg":"trace[1481261864] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"175.96011ms","start":"2026-10-13T13:46:45.181307Z","end":"2026-10-13T13:46:45.357267Z","steps":["trace[1481261864] 'process raft request' (duration: 175.506837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T13:46:45.357516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.924085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-13T13:46:45.357589Z","caller":"traceutil/trace.go:171","msg":"trace[2103223492] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26745; }","duration":"123.035997ms","start":"2026-10-13T13:46:45.234536Z","end":"2026-10-13T13:46:45.357572Z","steps":["trace[2103223492] 'agreement among raft nodes before linearized reading' (duration: 122.77091ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.530378Z","caller":"traceutil/trace.go:171","msg":"trace[1793287092] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"127.371737ms","start":"2026-10-13T13:46:45.402947Z","end":"2026-10-13T13:46:45.530319Z","steps":["trace[1793287092] 'process raft request' (duration: 127.13873ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:46:45.559498Z","caller":"traceutil/trace.go:171","msg":"trace[783277313] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"156.014203ms","start":"2026-10-13T13:46:45.403443Z","end":"2026-10-13T13:46:45.559457Z","steps":["trace[783277313] 'process raft request' (duration: 155.883119ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:47:08.264404Z","caller":"traceutil/trace.go:171","msg":"trace[25213009] transaction","detail":"{read_only:false; response_revision:27060; number_of_response:1; }","duration":"104.21618ms","start":"2026-10-13T13:47:08.160107Z","end":"2026-10-13T13:47:08.264323Z","steps":["trace[25213009] 'process raft request' (duration: 103.773668ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T13:48:21.426976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24864} {"level":"info","ts":"2026-10-13T13:48:21.584704Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24864,"took":"153.222896ms","hash":1170661830,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":36339712,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-13T13:48:21.584774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170661830,"revision":24864,"compact-revision":21645} {"level":"info","ts":"2026-10-13T13:50:32.046391Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"warn","ts":"2026-10-13T13:50:32.184839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.8547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openstack-database-exporter-78c69bc7dd-cs44k\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-10-13T13:50:32.184952Z","caller":"traceutil/trace.go:171","msg":"trace[83293306] range","detail":"{range_begin:/registry/pods/openstack/openstack-database-exporter-78c69bc7dd-cs44k; range_end:; response_count:1; response_revision:29069; }","duration":"166.984493ms","start":"2026-10-13T13:50:32.017944Z","end":"2026-10-13T13:50:32.184928Z","steps":["trace[83293306] 'agreement among raft nodes before linearized reading' (duration: 29.126169ms)","trace[83293306] 'range keys from in-memory index tree' (duration: 137.594306ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T13:50:32.227395Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-13T13:50:32.227605Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-13T13:53:21.436079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27692} {"level":"info","ts":"2026-10-13T13:53:21.573478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27692,"took":"132.63909ms","hash":2586938299,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":37249024,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-13T13:53:21.573533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2586938299,"revision":27692,"compact-revision":24864} {"level":"info","ts":"2026-10-13T13:58:21.44336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30331} {"level":"info","ts":"2026-10-13T13:58:21.588633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30331,"took":"141.088192ms","hash":1304078673,"current-db-size-bytes":58875904,"current-db-size":"59 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T13:58:21.588708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1304078673,"revision":30331,"compact-revision":27692} {"level":"info","ts":"2026-10-13T14:03:21.451764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32588} {"level":"info","ts":"2026-10-13T14:03:21.623546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32588,"took":"167.489258ms","hash":4140309820,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T14:03:21.623616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4140309820,"revision":32588,"compact-revision":30331} {"level":"info","ts":"2026-10-13T14:08:21.459171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34888} {"level":"info","ts":"2026-10-13T14:08:21.600986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34888,"took":"137.580037ms","hash":3551690072,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T14:08:21.601062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3551690072,"revision":34888,"compact-revision":32588} {"level":"info","ts":"2026-10-13T14:11:46.743966Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c665390cd9eacc14","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T14:11:46.750687Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-13T14:11:46.750791Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-13T14:13:21.467182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37212} {"level":"info","ts":"2026-10-13T14:13:21.604376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37212,"took":"133.109267ms","hash":3076722096,"current-db-size-bytes":60342272,"current-db-size":"60 MB","current-db-size-in-use-bytes":33284096,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-13T14:13:21.604526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3076722096,"revision":37212,"compact-revision":34888} {"level":"info","ts":"2026-10-13T14:18:21.474812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39494} {"level":"info","ts":"2026-10-13T14:18:21.618845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39494,"took":"138.677289ms","hash":3716101593,"current-db-size-bytes":61190144,"current-db-size":"61 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T14:18:21.618918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3716101593,"revision":39494,"compact-revision":37212}