{"level":"warn","ts":"2026-09-22T01:57:23.721508Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:57:23.721623Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.6: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.6:2380","--initial-cluster=instance=https://162.253.55.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.6: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-09-22T01:57:23.721788Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:57:23.7218Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.6:2380"]} {"level":"info","ts":"2026-09-22T01:57:23.721836Z","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-09-22T01:57:23.722682Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"]} {"level":"info","ts":"2026-09-22T01:57:23.722764Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.6:2380"],"listen-peer-urls":["https://162.253.55.6:2380"],"advertise-client-urls":["https://162.253.55.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.6: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-09-22T01:57:23.729351Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.381641ms"} {"level":"info","ts":"2026-09-22T01:57:23.74063Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e4145bf1b1a19cd","cluster-id":"e02e9056d18cef61"} {"level":"info","ts":"2026-09-22T01:57:23.740733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:57:23.740774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became follower at term 0"} {"level":"info","ts":"2026-09-22T01:57:23.740794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e4145bf1b1a19cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:57:23.740811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became follower at term 1"} {"level":"info","ts":"2026-09-22T01:57:23.740889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=(5638864895540337101)"} {"level":"warn","ts":"2026-09-22T01:57:23.75331Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:57:23.756281Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:57:23.757743Z","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-09-22T01:57:23.765138Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e4145bf1b1a19cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:57:23.765886Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e4145bf1b1a19cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:57:23.765997Z","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-09-22T01:57:23.766092Z","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-09-22T01:57:23.766103Z","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-09-22T01:57:23.767615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd switched to configuration voters=(5638864895540337101)"} {"level":"info","ts":"2026-09-22T01:57:23.767813Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e02e9056d18cef61","local-member-id":"4e4145bf1b1a19cd","added-peer-id":"4e4145bf1b1a19cd","added-peer-peer-urls":["https://162.253.55.6:2380"]} {"level":"info","ts":"2026-09-22T01:57:23.771567Z","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-09-22T01:57:23.771804Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e4145bf1b1a19cd","initial-advertise-peer-urls":["https://162.253.55.6:2380"],"listen-peer-urls":["https://162.253.55.6:2380"],"advertise-client-urls":["https://162.253.55.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:57:23.772034Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.6:2380"} {"level":"info","ts":"2026-09-22T01:57:23.773515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.6:2380"} {"level":"info","ts":"2026-09-22T01:57:23.772572Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:57:24.242054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:57:24.242161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:57:24.242191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd received MsgPreVoteResp from 4e4145bf1b1a19cd at term 1"} {"level":"info","ts":"2026-09-22T01:57:24.242215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:57:24.24224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd received MsgVoteResp from 4e4145bf1b1a19cd at term 2"} {"level":"info","ts":"2026-09-22T01:57:24.242263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e4145bf1b1a19cd became leader at term 2"} {"level":"info","ts":"2026-09-22T01:57:24.242278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e4145bf1b1a19cd elected leader 4e4145bf1b1a19cd at term 2"} {"level":"info","ts":"2026-09-22T01:57:24.336551Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e4145bf1b1a19cd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.6:2379]}","request-path":"/0/members/4e4145bf1b1a19cd/attributes","cluster-id":"e02e9056d18cef61","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:57:24.336614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:57:24.336697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:57:24.336799Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:24.337056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:57:24.337179Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:57:24.340419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:57:24.341543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.6:2379"} {"level":"info","ts":"2026-09-22T01:57:24.341647Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e02e9056d18cef61","local-member-id":"4e4145bf1b1a19cd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:24.341782Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:24.341846Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:57:32.025922Z","caller":"traceutil/trace.go:171","msg":"trace[1529830815] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"112.809282ms","start":"2026-09-22T01:57:31.913091Z","end":"2026-09-22T01:57:32.0259Z","steps":["trace[1529830815] 'read index received' (duration: 112.695951ms)","trace[1529830815] 'applied index is now lower than readState.Index' (duration: 112.391µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:32.026059Z","caller":"traceutil/trace.go:171","msg":"trace[350708821] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"127.244394ms","start":"2026-09-22T01:57:31.898768Z","end":"2026-09-22T01:57:32.026012Z","steps":["trace[350708821] 'process raft request' (duration: 127.00669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:32.02615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.054126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-22T01:57:32.02622Z","caller":"traceutil/trace.go:171","msg":"trace[201688623] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"113.143707ms","start":"2026-09-22T01:57:31.913066Z","end":"2026-09-22T01:57:32.026209Z","steps":["trace[201688623] 'agreement among raft nodes before linearized reading' (duration: 113.008915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:32.353931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.764996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:57:32.354101Z","caller":"traceutil/trace.go:171","msg":"trace[1829290412] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"300.679477ms","start":"2026-09-22T01:57:32.0534Z","end":"2026-09-22T01:57:32.354079Z","steps":["trace[1829290412] 'read index received' (duration: 54.025138ms)","trace[1829290412] 'applied index is now lower than readState.Index' (duration: 246.652659ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:32.354128Z","caller":"traceutil/trace.go:171","msg":"trace[914912082] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"324.126657ms","start":"2026-09-22T01:57:32.02997Z","end":"2026-09-22T01:57:32.354096Z","steps":["trace[914912082] 'process raft request' (duration: 77.537318ms)","trace[914912082] 'compare' (duration: 245.570513ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:32.354307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.881183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-09-22T01:57:32.354308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.910521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-22T01:57:32.354333Z","caller":"traceutil/trace.go:171","msg":"trace[862021170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:290; }","duration":"135.946133ms","start":"2026-09-22T01:57:32.21838Z","end":"2026-09-22T01:57:32.354326Z","steps":["trace[862021170] 'agreement among raft nodes before linearized reading' (duration: 135.845942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:32.354253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:32.029947Z","time spent":"324.246878ms","remote":"127.0.0.1:50808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:57:32.354349Z","caller":"traceutil/trace.go:171","msg":"trace[720604754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:290; }","duration":"300.990962ms","start":"2026-09-22T01:57:32.053346Z","end":"2026-09-22T01:57:32.354337Z","steps":["trace[720604754] 'agreement among raft nodes before linearized reading' (duration: 300.881551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:32.354383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:32.053326Z","time spent":"301.048233ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T01:57:32.691981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.261349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:57:32.692167Z","caller":"traceutil/trace.go:171","msg":"trace[42885516] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"333.286767ms","start":"2026-09-22T01:57:32.358861Z","end":"2026-09-22T01:57:32.692147Z","steps":["trace[42885516] 'process raft request' (duration: 333.227817ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:32.692156Z","caller":"traceutil/trace.go:171","msg":"trace[1550468632] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"334.651038ms","start":"2026-09-22T01:57:32.357466Z","end":"2026-09-22T01:57:32.692117Z","steps":["trace[1550468632] 'process raft request' (duration: 165.976553ms)","trace[1550468632] 'compare' (duration: 168.106966ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:32.692239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:32.358839Z","time spent":"333.369808ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:57:32.692261Z","caller":"traceutil/trace.go:171","msg":"trace[1407889012] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"334.692159ms","start":"2026-09-22T01:57:32.357547Z","end":"2026-09-22T01:57:32.692239Z","steps":["trace[1407889012] 'read index received' (duration: 165.890552ms)","trace[1407889012] 'applied index is now lower than readState.Index' (duration: 168.800227ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:32.692408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.831281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:57:32.69241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.945128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-22T01:57:32.692438Z","caller":"traceutil/trace.go:171","msg":"trace[741692981] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:292; }","duration":"334.924642ms","start":"2026-09-22T01:57:32.357501Z","end":"2026-09-22T01:57:32.692426Z","steps":["trace[741692981] 'agreement among raft nodes before linearized reading' (duration: 334.845691ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:32.692441Z","caller":"traceutil/trace.go:171","msg":"trace[1722513042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:292; }","duration":"214.01811ms","start":"2026-09-22T01:57:32.478413Z","end":"2026-09-22T01:57:32.692431Z","steps":["trace[1722513042] 'agreement among raft nodes before linearized reading' (duration: 213.960699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:32.692436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:32.357448Z","time spent":"334.79146ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:57:32.692455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:32.357476Z","time spent":"334.975563ms","remote":"127.0.0.1:50808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-09-22T01:58:02.182508Z","caller":"traceutil/trace.go:171","msg":"trace[1572809274] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"143.127375ms","start":"2026-09-22T01:58:02.03936Z","end":"2026-09-22T01:58:02.182487Z","steps":["trace[1572809274] 'process raft request' (duration: 142.994543ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.190814Z","caller":"traceutil/trace.go:171","msg":"trace[848088757] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"146.539913ms","start":"2026-09-22T01:58:02.044251Z","end":"2026-09-22T01:58:02.190791Z","steps":["trace[848088757] 'process raft request' (duration: 146.106446ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.191182Z","caller":"traceutil/trace.go:171","msg":"trace[1812124251] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"136.726246ms","start":"2026-09-22T01:58:02.054381Z","end":"2026-09-22T01:58:02.191108Z","steps":["trace[1812124251] 'process raft request' (duration: 136.139258ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.191438Z","caller":"traceutil/trace.go:171","msg":"trace[1505842637] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"130.832983ms","start":"2026-09-22T01:58:02.060559Z","end":"2026-09-22T01:58:02.191392Z","steps":["trace[1505842637] 'process raft request' (duration: 130.196714ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.191583Z","caller":"traceutil/trace.go:171","msg":"trace[1789975851] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"125.211165ms","start":"2026-09-22T01:58:02.066362Z","end":"2026-09-22T01:58:02.191573Z","steps":["trace[1789975851] 'process raft request' (duration: 124.984621ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.191878Z","caller":"traceutil/trace.go:171","msg":"trace[1359126463] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"130.078692ms","start":"2026-09-22T01:58:02.061766Z","end":"2026-09-22T01:58:02.191845Z","steps":["trace[1359126463] 'process raft request' (duration: 129.517174ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.192079Z","caller":"traceutil/trace.go:171","msg":"trace[1384155449] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"124.159249ms","start":"2026-09-22T01:58:02.067897Z","end":"2026-09-22T01:58:02.192056Z","steps":["trace[1384155449] 'process raft request' (duration: 123.569191ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.192235Z","caller":"traceutil/trace.go:171","msg":"trace[1547195541] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"121.358891ms","start":"2026-09-22T01:58:02.070866Z","end":"2026-09-22T01:58:02.192225Z","steps":["trace[1547195541] 'process raft request' (duration: 120.637851ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:02.192369Z","caller":"traceutil/trace.go:171","msg":"trace[279938906] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"117.342384ms","start":"2026-09-22T01:58:02.074938Z","end":"2026-09-22T01:58:02.192281Z","steps":["trace[279938906] 'process raft request' (duration: 116.616664ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:04.404084Z","caller":"traceutil/trace.go:171","msg":"trace[1895695992] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"130.778394ms","start":"2026-09-22T01:58:04.273276Z","end":"2026-09-22T01:58:04.404054Z","steps":["trace[1895695992] 'process raft request' (duration: 73.916591ms)","trace[1895695992] 'compare' (duration: 56.722551ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:58:08.394692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.964887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T01:58:08.394773Z","caller":"traceutil/trace.go:171","msg":"trace[645831661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"149.08224ms","start":"2026-09-22T01:58:08.245672Z","end":"2026-09-22T01:58:08.394754Z","steps":["trace[645831661] 'range keys from in-memory index tree' (duration: 148.812906ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:08.665459Z","caller":"traceutil/trace.go:171","msg":"trace[498040305] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"122.851687ms","start":"2026-09-22T01:58:08.542584Z","end":"2026-09-22T01:58:08.665436Z","steps":["trace[498040305] 'process raft request' (duration: 122.783896ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:08.665513Z","caller":"traceutil/trace.go:171","msg":"trace[1931724201] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"259.185371ms","start":"2026-09-22T01:58:08.406298Z","end":"2026-09-22T01:58:08.665483Z","steps":["trace[1931724201] 'process raft request' (duration: 235.894589ms)","trace[1931724201] 'compare' (duration: 22.670383ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:58:09.2023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.525788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:58:09.20242Z","caller":"traceutil/trace.go:171","msg":"trace[188030835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"269.68045ms","start":"2026-09-22T01:58:08.932711Z","end":"2026-09-22T01:58:09.202391Z","steps":["trace[188030835] 'range keys from in-memory index tree' (duration: 269.459327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:09.202433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.197714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-22T01:58:09.202565Z","caller":"traceutil/trace.go:171","msg":"trace[269862385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"327.375067ms","start":"2026-09-22T01:58:08.875159Z","end":"2026-09-22T01:58:09.202534Z","steps":["trace[269862385] 'range keys from in-memory index tree' (duration: 326.934591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:09.20262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:58:08.875151Z","time spent":"327.459398ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T01:58:09.202633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.709465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-22T01:58:09.202599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.010039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-22T01:58:09.202718Z","caller":"traceutil/trace.go:171","msg":"trace[1238050889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"328.843497ms","start":"2026-09-22T01:58:08.873852Z","end":"2026-09-22T01:58:09.202695Z","steps":["trace[1238050889] 'range keys from in-memory index tree' (duration: 328.541303ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:09.202739Z","caller":"traceutil/trace.go:171","msg":"trace[482031208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"279.202651ms","start":"2026-09-22T01:58:08.923509Z","end":"2026-09-22T01:58:09.202712Z","steps":["trace[482031208] 'range keys from in-memory index tree' (duration: 278.777906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:09.20276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:58:08.873822Z","time spent":"328.929188ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T01:58:09.203351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.818886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k76tl\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-09-22T01:58:09.203473Z","caller":"traceutil/trace.go:171","msg":"trace[467146229] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k76tl; range_end:; response_count:1; response_revision:584; }","duration":"320.997679ms","start":"2026-09-22T01:58:08.882439Z","end":"2026-09-22T01:58:09.203437Z","steps":["trace[467146229] 'range keys from in-memory index tree' (duration: 319.877823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:09.203579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:58:08.882408Z","time spent":"321.159201ms","remote":"127.0.0.1:50786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-k76tl\" "} {"level":"info","ts":"2026-09-22T01:58:09.22275Z","caller":"traceutil/trace.go:171","msg":"trace[31818841] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"154.395378ms","start":"2026-09-22T01:58:09.068279Z","end":"2026-09-22T01:58:09.222674Z","steps":["trace[31818841] 'process raft request' (duration: 153.531716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:26.408562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.17672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4h677\" ","response":"range_response_count:1 size:5045"} {"level":"info","ts":"2026-09-22T01:58:26.40865Z","caller":"traceutil/trace.go:171","msg":"trace[2126120087] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-4h677; range_end:; response_count:1; response_revision:758; }","duration":"291.308942ms","start":"2026-09-22T01:58:26.117321Z","end":"2026-09-22T01:58:26.40863Z","steps":["trace[2126120087] 'range keys from in-memory index tree' (duration: 290.994778ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:09.159339Z","caller":"traceutil/trace.go:171","msg":"trace[309552959] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"112.175823ms","start":"2026-09-22T01:59:09.046947Z","end":"2026-09-22T01:59:09.159123Z","steps":["trace[309552959] 'process raft request' (duration: 105.177905ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:09.170929Z","caller":"traceutil/trace.go:171","msg":"trace[312795798] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"123.410124ms","start":"2026-09-22T01:59:09.0475Z","end":"2026-09-22T01:59:09.170911Z","steps":["trace[312795798] 'process raft request' (duration: 123.145651ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:09.171037Z","caller":"traceutil/trace.go:171","msg":"trace[1516740368] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"119.119431ms","start":"2026-09-22T01:59:09.051898Z","end":"2026-09-22T01:59:09.171017Z","steps":["trace[1516740368] 'process raft request' (duration: 118.917028ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:44.967694Z","caller":"traceutil/trace.go:171","msg":"trace[489320402] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"494.232425ms","start":"2026-09-22T01:59:44.473439Z","end":"2026-09-22T01:59:44.967672Z","steps":["trace[489320402] 'process raft request' (duration: 494.085913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:44.967872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:44.473415Z","time spent":"494.371497ms","remote":"127.0.0.1:50786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3730,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:59:45.508765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.853582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:59:45.509123Z","caller":"traceutil/trace.go:171","msg":"trace[257213871] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1901; }","duration":"607.609354ms","start":"2026-09-22T01:59:44.90147Z","end":"2026-09-22T01:59:45.509079Z","steps":["trace[257213871] 'read index received' (duration: 66.054891ms)","trace[257213871] 'applied index is now lower than readState.Index' (duration: 541.553013ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:45.509244Z","caller":"traceutil/trace.go:171","msg":"trace[2028576639] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"868.89839ms","start":"2026-09-22T01:59:44.640333Z","end":"2026-09-22T01:59:45.509231Z","steps":["trace[2028576639] 'process raft request' (duration: 681.440321ms)","trace[2028576639] 'compare' (duration: 186.615399ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:45.509404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:44.640302Z","time spent":"868.975151ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:59:45.509403Z","caller":"traceutil/trace.go:171","msg":"trace[1439731297] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"738.56272ms","start":"2026-09-22T01:59:44.770811Z","end":"2026-09-22T01:59:45.509374Z","steps":["trace[1439731297] 'process raft request' (duration: 738.064244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:45.509495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.093346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:59:45.509566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:44.770774Z","time spent":"738.698101ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:59:45.509576Z","caller":"traceutil/trace.go:171","msg":"trace[490976761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"576.183047ms","start":"2026-09-22T01:59:44.933373Z","end":"2026-09-22T01:59:45.509557Z","steps":["trace[490976761] 'agreement among raft nodes before linearized reading' (duration: 576.015665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:45.50962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:44.933312Z","time spent":"576.299819ms","remote":"127.0.0.1:50624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:59:45.509558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.088254ms","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-09-22T01:59:45.509688Z","caller":"traceutil/trace.go:171","msg":"trace[1259616452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1830; }","duration":"116.275166ms","start":"2026-09-22T01:59:45.393399Z","end":"2026-09-22T01:59:45.509674Z","steps":["trace[1259616452] 'agreement among raft nodes before linearized reading' (duration: 116.000383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:45.509639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.666046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f7d5dfc8-f650-441d-9bd8-74c9c8670b65\" ","response":"range_response_count:1 size:3745"} {"level":"warn","ts":"2026-09-22T01:59:45.509558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.078039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-22T01:59:45.509755Z","caller":"traceutil/trace.go:171","msg":"trace[1275482584] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"608.332992ms","start":"2026-09-22T01:59:44.901408Z","end":"2026-09-22T01:59:45.509741Z","steps":["trace[1275482584] 'agreement among raft nodes before linearized reading' (duration: 607.942667ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:45.509752Z","caller":"traceutil/trace.go:171","msg":"trace[1954008672] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f7d5dfc8-f650-441d-9bd8-74c9c8670b65; range_end:; response_count:1; response_revision:1830; }","duration":"268.844278ms","start":"2026-09-22T01:59:45.240884Z","end":"2026-09-22T01:59:45.509728Z","steps":["trace[1954008672] 'agreement among raft nodes before linearized reading' (duration: 268.530674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:45.509791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:44.901382Z","time spent":"608.400473ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-22T01:59:45.509819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.528158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-22T01:59:45.509855Z","caller":"traceutil/trace.go:171","msg":"trace[1690989056] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1830; }","duration":"530.608528ms","start":"2026-09-22T01:59:44.979233Z","end":"2026-09-22T01:59:45.509841Z","steps":["trace[1690989056] 'agreement among raft nodes before linearized reading' (duration: 530.407386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:45.509884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:44.97921Z","time spent":"530.667969ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-22T01:59:45.51002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.576408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T01:59:45.510129Z","caller":"traceutil/trace.go:171","msg":"trace[1479169015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1830; }","duration":"102.684389ms","start":"2026-09-22T01:59:45.407433Z","end":"2026-09-22T01:59:45.510117Z","steps":["trace[1479169015] 'agreement among raft nodes before linearized reading' (duration: 102.173173ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:45.821049Z","caller":"traceutil/trace.go:171","msg":"trace[1754304591] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"209.091525ms","start":"2026-09-22T01:59:45.61193Z","end":"2026-09-22T01:59:45.821021Z","steps":["trace[1754304591] 'process raft request' (duration: 208.940003ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:19.935706Z","caller":"traceutil/trace.go:171","msg":"trace[342752177] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"125.013707ms","start":"2026-09-22T02:00:19.810663Z","end":"2026-09-22T02:00:19.935677Z","steps":["trace[342752177] 'process raft request' (duration: 124.783937ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:19.941349Z","caller":"traceutil/trace.go:171","msg":"trace[1061925968] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"130.662267ms","start":"2026-09-22T02:00:19.810669Z","end":"2026-09-22T02:00:19.941331Z","steps":["trace[1061925968] 'process raft request' (duration: 130.526211ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:19.941871Z","caller":"traceutil/trace.go:171","msg":"trace[1795614846] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"129.388082ms","start":"2026-09-22T02:00:19.812465Z","end":"2026-09-22T02:00:19.941854Z","steps":["trace[1795614846] 'process raft request' (duration: 129.313808ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:19.942055Z","caller":"traceutil/trace.go:171","msg":"trace[29249905] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"131.209552ms","start":"2026-09-22T02:00:19.810796Z","end":"2026-09-22T02:00:19.942005Z","steps":["trace[29249905] 'process raft request' (duration: 130.501161ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:19.94214Z","caller":"traceutil/trace.go:171","msg":"trace[1841372643] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"129.783328ms","start":"2026-09-22T02:00:19.812336Z","end":"2026-09-22T02:00:19.942119Z","steps":["trace[1841372643] 'process raft request' (duration: 129.240114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:20.426185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.816052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:00:20.426396Z","caller":"traceutil/trace.go:171","msg":"trace[877809760] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"161.564792ms","start":"2026-09-22T02:00:20.264809Z","end":"2026-09-22T02:00:20.426374Z","steps":["trace[877809760] 'process raft request' (duration: 161.486958ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:20.426883Z","caller":"traceutil/trace.go:171","msg":"trace[1580020410] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"388.326835ms","start":"2026-09-22T02:00:20.038528Z","end":"2026-09-22T02:00:20.426855Z","steps":["trace[1580020410] 'read index received' (duration: 122.640795ms)","trace[1580020410] 'applied index is now lower than readState.Index' (duration: 265.68296ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:20.42689Z","caller":"traceutil/trace.go:171","msg":"trace[294315103] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"456.162791ms","start":"2026-09-22T02:00:19.970704Z","end":"2026-09-22T02:00:20.426867Z","steps":["trace[294315103] 'process raft request' (duration: 190.543645ms)","trace[294315103] 'compare' (duration: 264.632203ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:00:20.427445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:19.970674Z","time spent":"456.695735ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:00:20.427599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.38517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-22T02:00:20.42766Z","caller":"traceutil/trace.go:171","msg":"trace[1588997888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2081; }","duration":"386.499994ms","start":"2026-09-22T02:00:20.041144Z","end":"2026-09-22T02:00:20.427644Z","steps":["trace[1588997888] 'agreement among raft nodes before linearized reading' (duration: 386.328897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:20.427049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.515043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-22T02:00:20.42771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:20.041129Z","time spent":"386.570307ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:00:20.427726Z","caller":"traceutil/trace.go:171","msg":"trace[1793672753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2081; }","duration":"389.227744ms","start":"2026-09-22T02:00:20.038478Z","end":"2026-09-22T02:00:20.427706Z","steps":["trace[1793672753] 'agreement among raft nodes before linearized reading' (duration: 388.44333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:20.427811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:20.038461Z","time spent":"389.334819ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:00:20.428155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.98072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-22T02:00:20.428209Z","caller":"traceutil/trace.go:171","msg":"trace[1523342590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2081; }","duration":"366.080065ms","start":"2026-09-22T02:00:20.062116Z","end":"2026-09-22T02:00:20.428196Z","steps":["trace[1523342590] 'agreement among raft nodes before linearized reading' (duration: 365.931688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:20.428248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:20.062102Z","time spent":"366.135077ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-22T02:06:50.401804Z","caller":"traceutil/trace.go:171","msg":"trace[502426689] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4848; }","duration":"130.84941ms","start":"2026-09-22T02:06:50.27093Z","end":"2026-09-22T02:06:50.40178Z","steps":["trace[502426689] 'read index received' (duration: 2.557755ms)","trace[502426689] 'applied index is now lower than readState.Index' (duration: 128.290745ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:50.40185Z","caller":"traceutil/trace.go:171","msg":"trace[351887902] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"131.311798ms","start":"2026-09-22T02:06:50.2705Z","end":"2026-09-22T02:06:50.401812Z","steps":["trace[351887902] 'process raft request' (duration: 103.454694ms)","trace[351887902] 'compare' (duration: 27.716631ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:50.402089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.136435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T02:06:50.402132Z","caller":"traceutil/trace.go:171","msg":"trace[382357825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4665; }","duration":"131.222146ms","start":"2026-09-22T02:06:50.270898Z","end":"2026-09-22T02:06:50.40212Z","steps":["trace[382357825] 'agreement among raft nodes before linearized reading' (duration: 131.007122ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.423066Z","caller":"traceutil/trace.go:171","msg":"trace[1817871607] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"110.138579ms","start":"2026-09-22T02:06:50.3129Z","end":"2026-09-22T02:06:50.423038Z","steps":["trace[1817871607] 'process raft request' (duration: 109.929815ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.423042Z","caller":"traceutil/trace.go:171","msg":"trace[567399528] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"135.521411ms","start":"2026-09-22T02:06:50.287438Z","end":"2026-09-22T02:06:50.42296Z","steps":["trace[567399528] 'process raft request' (duration: 135.209826ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:50.578221Z","caller":"traceutil/trace.go:171","msg":"trace[1681581319] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"143.777497ms","start":"2026-09-22T02:06:50.434419Z","end":"2026-09-22T02:06:50.578196Z","steps":["trace[1681581319] 'process raft request' (duration: 90.244334ms)","trace[1681581319] 'compare' (duration: 53.38729ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:50.578936Z","caller":"traceutil/trace.go:171","msg":"trace[1285444707] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4856; }","duration":"124.640393ms","start":"2026-09-22T02:06:50.454283Z","end":"2026-09-22T02:06:50.578924Z","steps":["trace[1285444707] 'read index received' (duration: 70.423418ms)","trace[1285444707] 'applied index is now lower than readState.Index' (duration: 54.216235ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:50.579042Z","caller":"traceutil/trace.go:171","msg":"trace[739439113] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"129.349435ms","start":"2026-09-22T02:06:50.449562Z","end":"2026-09-22T02:06:50.578912Z","steps":["trace[739439113] 'process raft request' (duration: 129.151212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:50.579246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.938939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-22T02:06:50.579332Z","caller":"traceutil/trace.go:171","msg":"trace[1476787802] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4674; }","duration":"125.092471ms","start":"2026-09-22T02:06:50.454216Z","end":"2026-09-22T02:06:50.579309Z","steps":["trace[1476787802] 'agreement among raft nodes before linearized reading' (duration: 124.843487ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:58.417545Z","caller":"traceutil/trace.go:171","msg":"trace[1021599590] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5053; }","duration":"132.989248ms","start":"2026-09-22T02:06:58.284532Z","end":"2026-09-22T02:06:58.417521Z","steps":["trace[1021599590] 'read index received' (duration: 78.422451ms)","trace[1021599590] 'applied index is now lower than readState.Index' (duration: 54.565937ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:06:58.417601Z","caller":"traceutil/trace.go:171","msg":"trace[1506001147] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"133.501097ms","start":"2026-09-22T02:06:58.284078Z","end":"2026-09-22T02:06:58.417579Z","steps":["trace[1506001147] 'process raft request' (duration: 78.976131ms)","trace[1506001147] 'compare' (duration: 54.349143ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:06:58.417709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.156881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:06:58.417742Z","caller":"traceutil/trace.go:171","msg":"trace[1540454187] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4866; }","duration":"133.229682ms","start":"2026-09-22T02:06:58.284501Z","end":"2026-09-22T02:06:58.41773Z","steps":["trace[1540454187] 'agreement among raft nodes before linearized reading' (duration: 133.14193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:06:58.417992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.668462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T02:06:58.418063Z","caller":"traceutil/trace.go:171","msg":"trace[1141961774] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4866; }","duration":"102.735213ms","start":"2026-09-22T02:06:58.315285Z","end":"2026-09-22T02:06:58.418021Z","steps":["trace[1141961774] 'agreement among raft nodes before linearized reading' (duration: 102.61114ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:59.516721Z","caller":"traceutil/trace.go:171","msg":"trace[719628433] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"142.545672ms","start":"2026-09-22T02:06:59.374118Z","end":"2026-09-22T02:06:59.516663Z","steps":["trace[719628433] 'process raft request' (duration: 91.998015ms)","trace[719628433] 'compare' (duration: 49.924046ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:07:07.863691Z","caller":"traceutil/trace.go:171","msg":"trace[580252661] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"177.313082ms","start":"2026-09-22T02:07:07.686354Z","end":"2026-09-22T02:07:07.863667Z","steps":["trace[580252661] 'process raft request' (duration: 177.15727ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:08.040975Z","caller":"traceutil/trace.go:171","msg":"trace[2020052795] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"351.582502ms","start":"2026-09-22T02:07:07.689369Z","end":"2026-09-22T02:07:08.040951Z","steps":["trace[2020052795] 'process raft request' (duration: 351.284337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:08.041116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:07:07.68935Z","time spent":"351.696693ms","remote":"127.0.0.1:50786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:07:08.041231Z","caller":"traceutil/trace.go:171","msg":"trace[547260644] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"345.287022ms","start":"2026-09-22T02:07:07.695934Z","end":"2026-09-22T02:07:08.041221Z","steps":["trace[547260644] 'process raft request' (duration: 344.875475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:08.041278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:07:07.695914Z","time spent":"345.341884ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2227,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T02:07:08.0411Z","caller":"traceutil/trace.go:171","msg":"trace[122309297] linearizableReadLoop","detail":"{readStateIndex:5280; appliedIndex:5278; }","duration":"339.742657ms","start":"2026-09-22T02:07:07.701325Z","end":"2026-09-22T02:07:08.041068Z","steps":["trace[122309297] 'read index received' (duration: 162.330743ms)","trace[122309297] 'applied index is now lower than readState.Index' (duration: 177.410484ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:07:08.041874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.51914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-22T02:07:08.043403Z","caller":"traceutil/trace.go:171","msg":"trace[1794529867] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5086; }","duration":"342.066097ms","start":"2026-09-22T02:07:07.701306Z","end":"2026-09-22T02:07:08.043372Z","steps":["trace[1794529867] 'agreement among raft nodes before linearized reading' (duration: 340.350328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:08.041924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.058783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T02:07:08.041987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.197534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.6\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-09-22T02:07:08.042987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.902669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362725"} {"level":"info","ts":"2026-09-22T02:07:08.044838Z","caller":"traceutil/trace.go:171","msg":"trace[283688145] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5086; }","duration":"341.749431ms","start":"2026-09-22T02:07:07.703061Z","end":"2026-09-22T02:07:08.044811Z","steps":["trace[283688145] 'agreement among raft nodes before linearized reading' (duration: 338.660838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:08.044901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:07:07.703017Z","time spent":"341.864053ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2362748,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-09-22T02:07:08.045709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:07:07.701296Z","time spent":"344.400507ms","remote":"127.0.0.1:50762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-09-22T02:07:08.045895Z","caller":"traceutil/trace.go:171","msg":"trace[1349728339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5086; }","duration":"113.081063ms","start":"2026-09-22T02:07:07.932798Z","end":"2026-09-22T02:07:08.045879Z","steps":["trace[1349728339] 'agreement among raft nodes before linearized reading' (duration: 109.028043ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:08.046026Z","caller":"traceutil/trace.go:171","msg":"trace[979195572] range","detail":"{range_begin:/registry/masterleases/162.253.55.6; range_end:; response_count:1; response_revision:5086; }","duration":"249.319505ms","start":"2026-09-22T02:07:07.796698Z","end":"2026-09-22T02:07:08.046017Z","steps":["trace[979195572] 'agreement among raft nodes before linearized reading' (duration: 245.148053ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:08.158345Z","caller":"traceutil/trace.go:171","msg":"trace[226772191] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"100.860091ms","start":"2026-09-22T02:07:08.057455Z","end":"2026-09-22T02:07:08.158315Z","steps":["trace[226772191] 'process raft request' (duration: 82.89621ms)","trace[226772191] 'compare' (duration: 17.528923ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:07:08.340769Z","caller":"traceutil/trace.go:171","msg":"trace[1348514467] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"108.282098ms","start":"2026-09-22T02:07:08.232462Z","end":"2026-09-22T02:07:08.340744Z","steps":["trace[1348514467] 'process raft request' (duration: 108.044374ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:08.379863Z","caller":"traceutil/trace.go:171","msg":"trace[1512924792] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"142.346016ms","start":"2026-09-22T02:07:08.237491Z","end":"2026-09-22T02:07:08.379837Z","steps":["trace[1512924792] 'process raft request' (duration: 142.116512ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:08.612037Z","caller":"traceutil/trace.go:171","msg":"trace[1464936382] linearizableReadLoop","detail":"{readStateIndex:5290; appliedIndex:5289; }","duration":"120.375548ms","start":"2026-09-22T02:07:08.491637Z","end":"2026-09-22T02:07:08.612013Z","steps":["trace[1464936382] 'read index received' (duration: 120.258516ms)","trace[1464936382] 'applied index is now lower than readState.Index' (duration: 116.042µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:07:08.612167Z","caller":"traceutil/trace.go:171","msg":"trace[571098370] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"124.468688ms","start":"2026-09-22T02:07:08.487648Z","end":"2026-09-22T02:07:08.612116Z","steps":["trace[571098370] 'process raft request' (duration: 124.224404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:08.612307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.658033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T02:07:08.612363Z","caller":"traceutil/trace.go:171","msg":"trace[1719858484] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5095; }","duration":"120.773545ms","start":"2026-09-22T02:07:08.491571Z","end":"2026-09-22T02:07:08.612345Z","steps":["trace[1719858484] 'agreement among raft nodes before linearized reading' (duration: 120.558251ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:08.662754Z","caller":"traceutil/trace.go:171","msg":"trace[784450473] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"125.038008ms","start":"2026-09-22T02:07:08.53766Z","end":"2026-09-22T02:07:08.662698Z","steps":["trace[784450473] 'process raft request' (duration: 116.839147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:08.663164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.173179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-22T02:07:08.663215Z","caller":"traceutil/trace.go:171","msg":"trace[1472887394] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5097; }","duration":"166.29191ms","start":"2026-09-22T02:07:08.496909Z","end":"2026-09-22T02:07:08.663201Z","steps":["trace[1472887394] 'agreement among raft nodes before linearized reading' (duration: 166.084576ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:20.60115Z","caller":"traceutil/trace.go:171","msg":"trace[1412971684] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"106.291314ms","start":"2026-09-22T02:07:20.494832Z","end":"2026-09-22T02:07:20.601123Z","steps":["trace[1412971684] 'process raft request' (duration: 105.117084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:20.931872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.226723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T02:07:20.931946Z","caller":"traceutil/trace.go:171","msg":"trace[702019597] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5254; }","duration":"208.358575ms","start":"2026-09-22T02:07:20.723572Z","end":"2026-09-22T02:07:20.931931Z","steps":["trace[702019597] 'range keys from in-memory index tree' (duration: 208.05504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:20.932008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.379376ms","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-09-22T02:07:20.932081Z","caller":"traceutil/trace.go:171","msg":"trace[241222416] linearizableReadLoop","detail":"{readStateIndex:5455; appliedIndex:5454; }","duration":"127.664021ms","start":"2026-09-22T02:07:20.804235Z","end":"2026-09-22T02:07:20.931899Z","steps":["trace[241222416] 'read index received' (duration: 116.479529ms)","trace[241222416] 'applied index is now lower than readState.Index' (duration: 11.182722ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:07:20.932107Z","caller":"traceutil/trace.go:171","msg":"trace[1150955656] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"197.251234ms","start":"2026-09-22T02:07:20.734813Z","end":"2026-09-22T02:07:20.932065Z","steps":["trace[1150955656] 'process raft request' (duration: 185.767947ms)","trace[1150955656] 'compare' (duration: 10.651693ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:07:20.932196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.960606ms","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-09-22T02:07:20.932093Z","caller":"traceutil/trace.go:171","msg":"trace[861999169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5254; }","duration":"201.561089ms","start":"2026-09-22T02:07:20.730513Z","end":"2026-09-22T02:07:20.932074Z","steps":["trace[861999169] 'range keys from in-memory index tree' (duration: 201.123641ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:20.932223Z","caller":"traceutil/trace.go:171","msg":"trace[1728311935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5255; }","duration":"128.008166ms","start":"2026-09-22T02:07:20.804206Z","end":"2026-09-22T02:07:20.932215Z","steps":["trace[1728311935] 'agreement among raft nodes before linearized reading' (duration: 127.925525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:07:20.933627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.788672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-09-22T02:07:20.933699Z","caller":"traceutil/trace.go:171","msg":"trace[1898551702] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5254; }","duration":"201.887324ms","start":"2026-09-22T02:07:20.731799Z","end":"2026-09-22T02:07:20.933686Z","steps":["trace[1898551702] 'range keys from in-memory index tree' (duration: 199.90432ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:07:24.385706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2846} {"level":"info","ts":"2026-09-22T02:07:24.471421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2846,"took":"83.920227ms","hash":4056633001,"current-db-size-bytes":51519488,"current-db-size":"52 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:07:24.471537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4056633001,"revision":2846,"compact-revision":-1} {"level":"warn","ts":"2026-09-22T02:08:28.641208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.559421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4724"} {"level":"info","ts":"2026-09-22T02:08:28.641692Z","caller":"traceutil/trace.go:171","msg":"trace[1152452256] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5753; }","duration":"120.094652ms","start":"2026-09-22T02:08:28.52157Z","end":"2026-09-22T02:08:28.641665Z","steps":["trace[1152452256] 'range keys from in-memory index tree' (duration: 119.311571ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:09:01.169373Z","caller":"traceutil/trace.go:171","msg":"trace[557600793] transaction","detail":"{read_only:false; response_revision:6004; number_of_response:1; }","duration":"229.266756ms","start":"2026-09-22T02:09:00.94007Z","end":"2026-09-22T02:09:01.169337Z","steps":["trace[557600793] 'process raft request' (duration: 229.078649ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:12:24.397178Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5306} {"level":"info","ts":"2026-09-22T02:12:24.475812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5306,"took":"77.059633ms","hash":3116199400,"current-db-size-bytes":51519488,"current-db-size":"52 MB","current-db-size-in-use-bytes":18747392,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-22T02:12:24.475888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3116199400,"revision":5306,"compact-revision":2846}