{"level":"warn","ts":"2026-09-01T01:55:14.424994Z","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-01T01:55:14.425101Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.45: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.45:2380","--initial-cluster=instance=https://162.253.55.45:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.45:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.45: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-01T01:55:14.42532Z","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-01T01:55:14.425338Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-09-01T01:55:14.425364Z","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-01T01:55:14.426845Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"]} {"level":"info","ts":"2026-09-01T01:55:14.427027Z","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.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.45: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-01T01:55:14.432524Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.095494ms"} {"level":"info","ts":"2026-09-01T01:55:14.440367Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fbe9b088d8de907a","cluster-id":"332637b4e3714c3a"} {"level":"info","ts":"2026-09-01T01:55:14.440473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T01:55:14.440584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 0"} {"level":"info","ts":"2026-09-01T01:55:14.440612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fbe9b088d8de907a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T01:55:14.440621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 1"} {"level":"info","ts":"2026-09-01T01:55:14.44072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"warn","ts":"2026-09-01T01:55:14.451264Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T01:55:14.45289Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T01:55:14.454168Z","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-01T01:55:14.456588Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fbe9b088d8de907a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T01:55:14.456764Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fbe9b088d8de907a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T01:55:14.456808Z","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-01T01:55:14.456958Z","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-01T01:55:14.462997Z","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-01T01:55:14.463308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"info","ts":"2026-09-01T01:55:14.46349Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","added-peer-id":"fbe9b088d8de907a","added-peer-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-09-01T01:55:14.464376Z","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-01T01:55:14.464581Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-09-01T01:55:14.464615Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-09-01T01:55:14.464915Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fbe9b088d8de907a","initial-advertise-peer-urls":["https://162.253.55.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T01:55:14.464956Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T01:55:14.641136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T01:55:14.6412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T01:55:14.641244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgPreVoteResp from fbe9b088d8de907a at term 1"} {"level":"info","ts":"2026-09-01T01:55:14.641264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became candidate at term 2"} {"level":"info","ts":"2026-09-01T01:55:14.641274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgVoteResp from fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-09-01T01:55:14.641295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became leader at term 2"} {"level":"info","ts":"2026-09-01T01:55:14.641308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fbe9b088d8de907a elected leader fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-09-01T01:55:14.643038Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fbe9b088d8de907a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.45:2379]}","request-path":"/0/members/fbe9b088d8de907a/attributes","cluster-id":"332637b4e3714c3a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T01:55:14.643068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T01:55:14.643247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T01:55:14.643822Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T01:55:14.644541Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T01:55:14.644608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T01:55:14.646784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.45:2379"} {"level":"info","ts":"2026-09-01T01:55:14.646825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T01:55:14.651296Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T01:55:14.651692Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T01:55:14.651746Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-01T01:55:17.703521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.402033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-01T01:55:17.703893Z","caller":"traceutil/trace.go:171","msg":"trace[1630783379] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"351.865522ms","start":"2026-09-01T01:55:17.352002Z","end":"2026-09-01T01:55:17.703867Z","steps":["trace[1630783379] 'process raft request' (duration: 167.482669ms)","trace[1630783379] 'compare' (duration: 183.247668ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:55:17.70412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:55:17.351982Z","time spent":"352.09374ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T01:55:18.070078Z","caller":"traceutil/trace.go:171","msg":"trace[1112644028] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"360.218628ms","start":"2026-09-01T01:55:17.709814Z","end":"2026-09-01T01:55:18.070032Z","steps":["trace[1112644028] 'process raft request' (duration: 303.142432ms)","trace[1112644028] 'compare' (duration: 56.907481ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:55:18.070334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:55:17.709793Z","time spent":"360.431934ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T01:55:53.714361Z","caller":"traceutil/trace.go:171","msg":"trace[1355573766] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"108.028981ms","start":"2026-09-01T01:55:53.606302Z","end":"2026-09-01T01:55:53.714331Z","steps":["trace[1355573766] 'process raft request' (duration: 41.613588ms)","trace[1355573766] 'compare' (duration: 66.277949ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T01:55:53.71951Z","caller":"traceutil/trace.go:171","msg":"trace[1997985403] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"107.875538ms","start":"2026-09-01T01:55:53.611623Z","end":"2026-09-01T01:55:53.719499Z","steps":["trace[1997985403] 'process raft request' (duration: 107.710744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:55:54.055529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.628924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T01:55:54.055805Z","caller":"traceutil/trace.go:171","msg":"trace[921991275] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"316.632739ms","start":"2026-09-01T01:55:53.73908Z","end":"2026-09-01T01:55:54.055713Z","steps":["trace[921991275] 'process raft request' (duration: 140.73516ms)","trace[921991275] 'compare' (duration: 175.440059ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T01:55:54.055955Z","caller":"traceutil/trace.go:171","msg":"trace[1331824191] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"316.749952ms","start":"2026-09-01T01:55:53.739152Z","end":"2026-09-01T01:55:54.055902Z","steps":["trace[1331824191] 'process raft request' (duration: 316.515266ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:55:54.056206Z","caller":"traceutil/trace.go:171","msg":"trace[1148705038] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"297.146502ms","start":"2026-09-01T01:55:53.759038Z","end":"2026-09-01T01:55:54.056185Z","steps":["trace[1148705038] 'process raft request' (duration: 296.810024ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:55:54.056481Z","caller":"traceutil/trace.go:171","msg":"trace[417695398] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"255.89745ms","start":"2026-09-01T01:55:53.800546Z","end":"2026-09-01T01:55:54.056443Z","steps":["trace[417695398] 'process raft request' (duration: 255.492501ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:55:54.056523Z","caller":"traceutil/trace.go:171","msg":"trace[641313184] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:567; }","duration":"311.615472ms","start":"2026-09-01T01:55:53.744886Z","end":"2026-09-01T01:55:54.056501Z","steps":["trace[641313184] 'read index received' (duration: 134.990655ms)","trace[641313184] 'applied index is now lower than readState.Index' (duration: 176.621837ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:55:54.056863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:55:53.73905Z","time spent":"317.448469ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T01:55:54.056481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:55:53.739137Z","time spent":"316.921646ms","remote":"127.0.0.1:56760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T01:55:54.063351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.491006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-vpgdp\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-09-01T01:55:54.063427Z","caller":"traceutil/trace.go:171","msg":"trace[1582606656] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-vpgdp; range_end:; response_count:1; response_revision:547; }","duration":"288.640991ms","start":"2026-09-01T01:55:53.774767Z","end":"2026-09-01T01:55:54.063408Z","steps":["trace[1582606656] 'agreement among raft nodes before linearized reading' (duration: 288.494937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:55:54.063505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.600255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T01:55:54.06357Z","caller":"traceutil/trace.go:171","msg":"trace[248251111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:547; }","duration":"318.710318ms","start":"2026-09-01T01:55:53.744841Z","end":"2026-09-01T01:55:54.063552Z","steps":["trace[248251111] 'agreement among raft nodes before linearized reading' (duration: 311.732905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:55:54.06361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:55:53.744829Z","time spent":"318.771179ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-01T01:55:55.263276Z","caller":"traceutil/trace.go:171","msg":"trace[638525899] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"104.194148ms","start":"2026-09-01T01:55:55.159057Z","end":"2026-09-01T01:55:55.263251Z","steps":["trace[638525899] 'process raft request' (duration: 95.394333ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:55:55.263461Z","caller":"traceutil/trace.go:171","msg":"trace[2120370700] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"100.539792ms","start":"2026-09-01T01:55:55.162643Z","end":"2026-09-01T01:55:55.263182Z","steps":["trace[2120370700] 'read index received' (duration: 91.83274ms)","trace[2120370700] 'applied index is now lower than readState.Index' (duration: 8.705652ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:55:55.263562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.87451ms","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-09-01T01:55:55.263666Z","caller":"traceutil/trace.go:171","msg":"trace[166227681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"101.047044ms","start":"2026-09-01T01:55:55.162595Z","end":"2026-09-01T01:55:55.263642Z","steps":["trace[166227681] 'agreement among raft nodes before linearized reading' (duration: 100.745897ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:01.568498Z","caller":"traceutil/trace.go:171","msg":"trace[713180625] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"140.550172ms","start":"2026-09-01T01:56:01.427884Z","end":"2026-09-01T01:56:01.568434Z","steps":["trace[713180625] 'process raft request' (duration: 139.260673ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:01.750831Z","caller":"traceutil/trace.go:171","msg":"trace[1980509239] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"174.047957ms","start":"2026-09-01T01:56:01.576752Z","end":"2026-09-01T01:56:01.7508Z","steps":["trace[1980509239] 'process raft request' (duration: 166.750404ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:01.754222Z","caller":"traceutil/trace.go:171","msg":"trace[190506330] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"102.550015ms","start":"2026-09-01T01:56:01.651656Z","end":"2026-09-01T01:56:01.754206Z","steps":["trace[190506330] 'process raft request' (duration: 102.466964ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:02.35232Z","caller":"traceutil/trace.go:171","msg":"trace[26778130] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"538.884984ms","start":"2026-09-01T01:56:01.813399Z","end":"2026-09-01T01:56:02.352284Z","steps":["trace[26778130] 'process raft request' (duration: 524.890405ms)","trace[26778130] 'compare' (duration: 13.074419ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:56:02.352599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:56:01.813369Z","time spent":"539.043328ms","remote":"127.0.0.1:56898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T01:56:02.354322Z","caller":"traceutil/trace.go:171","msg":"trace[626404165] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"221.205285ms","start":"2026-09-01T01:56:02.133084Z","end":"2026-09-01T01:56:02.35429Z","steps":["trace[626404165] 'read index received' (duration: 205.291894ms)","trace[626404165] 'applied index is now lower than readState.Index' (duration: 15.911921ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:56:02.354563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.447761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-01T01:56:02.354597Z","caller":"traceutil/trace.go:171","msg":"trace[1096826775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"221.551403ms","start":"2026-09-01T01:56:02.133034Z","end":"2026-09-01T01:56:02.354585Z","steps":["trace[1096826775] 'agreement among raft nodes before linearized reading' (duration: 221.370589ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:02.354946Z","caller":"traceutil/trace.go:171","msg":"trace[1243773813] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"114.553775ms","start":"2026-09-01T01:56:02.240347Z","end":"2026-09-01T01:56:02.354901Z","steps":["trace[1243773813] 'process raft request' (duration: 113.385099ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:02.355185Z","caller":"traceutil/trace.go:171","msg":"trace[1392657030] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"467.649097ms","start":"2026-09-01T01:56:01.887495Z","end":"2026-09-01T01:56:02.355145Z","steps":["trace[1392657030] 'process raft request' (duration: 465.813206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:56:02.35592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:56:01.88748Z","time spent":"467.8064ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T01:56:19.497375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.471467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T01:56:19.49746Z","caller":"traceutil/trace.go:171","msg":"trace[785212880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"144.601709ms","start":"2026-09-01T01:56:19.352842Z","end":"2026-09-01T01:56:19.497443Z","steps":["trace[785212880] 'range keys from in-memory index tree' (duration: 144.370545ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:56:50.766262Z","caller":"traceutil/trace.go:171","msg":"trace[955275154] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"150.485048ms","start":"2026-09-01T01:56:50.615751Z","end":"2026-09-01T01:56:50.766236Z","steps":["trace[955275154] 'process raft request' (duration: 150.348536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:57:38.50978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.743169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-01T01:57:38.509937Z","caller":"traceutil/trace.go:171","msg":"trace[376864165] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1855; }","duration":"104.916442ms","start":"2026-09-01T01:57:38.404987Z","end":"2026-09-01T01:57:38.509903Z","steps":["trace[376864165] 'range keys from in-memory index tree' (duration: 104.1417ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:57:38.790595Z","caller":"traceutil/trace.go:171","msg":"trace[901363173] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"275.56575ms","start":"2026-09-01T01:57:38.514996Z","end":"2026-09-01T01:57:38.790561Z","steps":["trace[901363173] 'process raft request' (duration: 275.332647ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:57:38.90535Z","caller":"traceutil/trace.go:171","msg":"trace[585528040] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"105.329238ms","start":"2026-09-01T01:57:38.799985Z","end":"2026-09-01T01:57:38.905314Z","steps":["trace[585528040] 'process raft request' (duration: 47.121306ms)","trace[585528040] 'compare' (duration: 58.06982ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T01:57:51.556456Z","caller":"traceutil/trace.go:171","msg":"trace[1707312501] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"115.457828ms","start":"2026-09-01T01:57:51.440965Z","end":"2026-09-01T01:57:51.556422Z","steps":["trace[1707312501] 'process raft request' (duration: 63.208935ms)","trace[1707312501] 'compare' (duration: 52.100741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:58:27.994467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.172743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T01:58:27.994553Z","caller":"traceutil/trace.go:171","msg":"trace[1434757575] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2172; }","duration":"300.333775ms","start":"2026-09-01T01:58:27.694201Z","end":"2026-09-01T01:58:27.994534Z","steps":["trace[1434757575] 'count revisions from in-memory index tree' (duration: 300.02182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:58:27.994593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:58:27.694174Z","time spent":"300.408036ms","remote":"127.0.0.1:56888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-09-01T01:58:27.99465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.252867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-01T01:58:27.994719Z","caller":"traceutil/trace.go:171","msg":"trace[466210199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2172; }","duration":"142.357819ms","start":"2026-09-01T01:58:27.852344Z","end":"2026-09-01T01:58:27.994702Z","steps":["trace[466210199] 'range keys from in-memory index tree' (duration: 142.074274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:58:27.995038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.790348ms","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-09-01T01:58:27.99519Z","caller":"traceutil/trace.go:171","msg":"trace[1575829660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2172; }","duration":"243.984902ms","start":"2026-09-01T01:58:27.751167Z","end":"2026-09-01T01:58:27.995152Z","steps":["trace[1575829660] 'range keys from in-memory index tree' (duration: 243.529825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:58:28.32056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.626889ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T01:58:28.320871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.299428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T01:58:28.32105Z","caller":"traceutil/trace.go:171","msg":"trace[2032985369] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"319.01087ms","start":"2026-09-01T01:58:28.002024Z","end":"2026-09-01T01:58:28.321035Z","steps":["trace[2032985369] 'read index received' (duration: 142.440729ms)","trace[2032985369] 'applied index is now lower than readState.Index' (duration: 176.568271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T01:58:28.32118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.718183ms","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-09-01T01:58:28.321107Z","caller":"traceutil/trace.go:171","msg":"trace[742838649] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"321.507714ms","start":"2026-09-01T01:58:27.999549Z","end":"2026-09-01T01:58:28.321057Z","steps":["trace[742838649] 'process raft request' (duration: 144.912353ms)","trace[742838649] 'compare' (duration: 175.931203ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T01:58:28.321215Z","caller":"traceutil/trace.go:171","msg":"trace[1586226791] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2174; }","duration":"348.781553ms","start":"2026-09-01T01:58:27.972421Z","end":"2026-09-01T01:58:28.321202Z","steps":["trace[1586226791] 'agreement among raft nodes before linearized reading' (duration: 348.696482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:58:28.321275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:58:27.972408Z","time spent":"348.834614ms","remote":"127.0.0.1:38974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-01T01:58:28.32106Z","caller":"traceutil/trace.go:171","msg":"trace[1515870198] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2173; }","duration":"224.998645ms","start":"2026-09-01T01:58:28.095878Z","end":"2026-09-01T01:58:28.320876Z","steps":["trace[1515870198] 'range keys from in-memory index tree' (duration: 224.601969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:58:28.322117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:58:27.999531Z","time spent":"321.713538ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T01:58:28.337736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.476375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T01:58:28.337813Z","caller":"traceutil/trace.go:171","msg":"trace[1502692741] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2175; }","duration":"236.582125ms","start":"2026-09-01T01:58:28.10121Z","end":"2026-09-01T01:58:28.337792Z","steps":["trace[1502692741] 'agreement among raft nodes before linearized reading' (duration: 236.398863ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:58:28.337966Z","caller":"traceutil/trace.go:171","msg":"trace[1367221138] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"331.163268ms","start":"2026-09-01T01:58:28.006771Z","end":"2026-09-01T01:58:28.337934Z","steps":["trace[1367221138] 'process raft request' (duration: 330.702142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:58:28.338156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T01:58:28.006754Z","time spent":"331.302289ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T01:59:42.025487Z","caller":"traceutil/trace.go:171","msg":"trace[464126284] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2778; }","duration":"280.780472ms","start":"2026-09-01T01:59:41.744681Z","end":"2026-09-01T01:59:42.025461Z","steps":["trace[464126284] 'read index received' (duration: 280.699679ms)","trace[464126284] 'applied index is now lower than readState.Index' (duration: 79.683µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T01:59:42.025797Z","caller":"traceutil/trace.go:171","msg":"trace[69813632] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"294.296381ms","start":"2026-09-01T01:59:41.731484Z","end":"2026-09-01T01:59:42.02578Z","steps":["trace[69813632] 'process raft request' (duration: 293.835205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:59:42.025995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.283839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-01T01:59:42.026067Z","caller":"traceutil/trace.go:171","msg":"trace[316738508] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2677; }","duration":"281.392133ms","start":"2026-09-01T01:59:41.744656Z","end":"2026-09-01T01:59:42.026049Z","steps":["trace[316738508] 'agreement among raft nodes before linearized reading' (duration: 281.138524ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T01:59:42.031575Z","caller":"traceutil/trace.go:171","msg":"trace[1450715917] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"114.355547ms","start":"2026-09-01T01:59:41.917188Z","end":"2026-09-01T01:59:42.031544Z","steps":["trace[1450715917] 'process raft request' (duration: 114.15468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T01:59:47.368223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.441155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-01T01:59:47.368316Z","caller":"traceutil/trace.go:171","msg":"trace[1053582941] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2707; }","duration":"122.578741ms","start":"2026-09-01T01:59:47.245714Z","end":"2026-09-01T01:59:47.368293Z","steps":["trace[1053582941] 'range keys from in-memory index tree' (duration: 122.282191ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:23.60411Z","caller":"traceutil/trace.go:171","msg":"trace[1789529226] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"212.81751ms","start":"2026-09-01T02:04:23.391253Z","end":"2026-09-01T02:04:23.604071Z","steps":["trace[1789529226] 'process raft request' (duration: 166.009481ms)","trace[1789529226] 'compare' (duration: 46.590595ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T02:04:23.604289Z","caller":"traceutil/trace.go:171","msg":"trace[1541256181] linearizableReadLoop","detail":"{readStateIndex:4853; appliedIndex:4850; }","duration":"183.686392ms","start":"2026-09-01T02:04:23.420565Z","end":"2026-09-01T02:04:23.604251Z","steps":["trace[1541256181] 'read index received' (duration: 1.457016ms)","trace[1541256181] 'applied index is now lower than readState.Index' (duration: 182.226566ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T02:04:23.604341Z","caller":"traceutil/trace.go:171","msg":"trace[491939793] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"211.784811ms","start":"2026-09-01T02:04:23.392488Z","end":"2026-09-01T02:04:23.604273Z","steps":["trace[491939793] 'process raft request' (duration: 211.538646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T02:04:23.604609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.013977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-pxn68\" ","response":"range_response_count:1 size:4331"} {"level":"info","ts":"2026-09-01T02:04:23.604675Z","caller":"traceutil/trace.go:171","msg":"trace[182712929] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-pxn68; range_end:; response_count:1; response_revision:4672; }","duration":"184.12557ms","start":"2026-09-01T02:04:23.420514Z","end":"2026-09-01T02:04:23.60464Z","steps":["trace[182712929] 'agreement among raft nodes before linearized reading' (duration: 183.873165ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:23.779151Z","caller":"traceutil/trace.go:171","msg":"trace[95233367] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"165.995271ms","start":"2026-09-01T02:04:23.61312Z","end":"2026-09-01T02:04:23.779116Z","steps":["trace[95233367] 'process raft request' (duration: 162.557398ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:29.063917Z","caller":"traceutil/trace.go:171","msg":"trace[848376595] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4951; }","duration":"106.685627ms","start":"2026-09-01T02:04:28.9572Z","end":"2026-09-01T02:04:29.063885Z","steps":["trace[848376595] 'read index received' (duration: 106.469233ms)","trace[848376595] 'applied index is now lower than readState.Index' (duration: 214.874µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T02:04:29.064948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.714256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:2109552"} {"level":"info","ts":"2026-09-01T02:04:29.06503Z","caller":"traceutil/trace.go:171","msg":"trace[925488424] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4768; }","duration":"107.824518ms","start":"2026-09-01T02:04:28.957189Z","end":"2026-09-01T02:04:29.065013Z","steps":["trace[925488424] 'agreement among raft nodes before linearized reading' (duration: 106.802669ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:29.258078Z","caller":"traceutil/trace.go:171","msg":"trace[182756916] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4952; }","duration":"177.862131ms","start":"2026-09-01T02:04:29.080173Z","end":"2026-09-01T02:04:29.258035Z","steps":["trace[182756916] 'read index received' (duration: 165.613849ms)","trace[182756916] 'applied index is now lower than readState.Index' (duration: 12.247002ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T02:04:29.258113Z","caller":"traceutil/trace.go:171","msg":"trace[1013502574] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"193.015394ms","start":"2026-09-01T02:04:29.06504Z","end":"2026-09-01T02:04:29.258055Z","steps":["trace[1013502574] 'process raft request' (duration: 180.699201ms)","trace[1013502574] 'compare' (duration: 12.12819ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T02:04:29.258418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.208717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-01T02:04:29.25846Z","caller":"traceutil/trace.go:171","msg":"trace[1249892837] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4769; }","duration":"178.317819ms","start":"2026-09-01T02:04:29.080125Z","end":"2026-09-01T02:04:29.258443Z","steps":["trace[1249892837] 'agreement among raft nodes before linearized reading' (duration: 178.058544ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:29.276886Z","caller":"traceutil/trace.go:171","msg":"trace[145200497] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"172.063975ms","start":"2026-09-01T02:04:29.104795Z","end":"2026-09-01T02:04:29.276859Z","steps":["trace[145200497] 'process raft request' (duration: 171.417193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T02:04:37.573119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.254469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T02:04:37.573239Z","caller":"traceutil/trace.go:171","msg":"trace[657807237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4977; }","duration":"221.439092ms","start":"2026-09-01T02:04:37.351775Z","end":"2026-09-01T02:04:37.573214Z","steps":["trace[657807237] 'range keys from in-memory index tree' (duration: 221.155398ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:46.217209Z","caller":"traceutil/trace.go:171","msg":"trace[78276829] linearizableReadLoop","detail":"{readStateIndex:5296; appliedIndex:5295; }","duration":"146.802336ms","start":"2026-09-01T02:04:46.070377Z","end":"2026-09-01T02:04:46.217179Z","steps":["trace[78276829] 'read index received' (duration: 74.149292ms)","trace[78276829] 'applied index is now lower than readState.Index' (duration: 72.651464ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T02:04:46.217273Z","caller":"traceutil/trace.go:171","msg":"trace[952118317] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"167.062957ms","start":"2026-09-01T02:04:46.050166Z","end":"2026-09-01T02:04:46.217229Z","steps":["trace[952118317] 'process raft request' (duration: 94.381872ms)","trace[952118317] 'compare' (duration: 72.39349ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T02:04:46.217556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.146373ms","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-01T02:04:46.2176Z","caller":"traceutil/trace.go:171","msg":"trace[81009435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5104; }","duration":"147.266124ms","start":"2026-09-01T02:04:46.070324Z","end":"2026-09-01T02:04:46.21759Z","steps":["trace[81009435] 'agreement among raft nodes before linearized reading' (duration: 147.057021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T02:04:46.217758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.653325ms","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-01T02:04:46.217871Z","caller":"traceutil/trace.go:171","msg":"trace[1113524003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5104; }","duration":"136.837368ms","start":"2026-09-01T02:04:46.080999Z","end":"2026-09-01T02:04:46.217837Z","steps":["trace[1113524003] 'agreement among raft nodes before linearized reading' (duration: 136.38714ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:53.471451Z","caller":"traceutil/trace.go:171","msg":"trace[1885533426] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"489.684854ms","start":"2026-09-01T02:04:52.981728Z","end":"2026-09-01T02:04:53.471413Z","steps":["trace[1885533426] 'process raft request' (duration: 489.403749ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:53.471812Z","caller":"traceutil/trace.go:171","msg":"trace[872103925] linearizableReadLoop","detail":"{readStateIndex:5363; appliedIndex:5363; }","duration":"197.997412ms","start":"2026-09-01T02:04:53.273726Z","end":"2026-09-01T02:04:53.471724Z","steps":["trace[872103925] 'read index received' (duration: 197.989852ms)","trace[872103925] 'applied index is now lower than readState.Index' (duration: 5.59µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T02:04:53.471844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T02:04:52.981707Z","time spent":"489.851377ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T02:04:53.472194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.044211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T02:04:53.472332Z","caller":"traceutil/trace.go:171","msg":"trace[1781106534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5169; }","duration":"119.170733ms","start":"2026-09-01T02:04:53.353082Z","end":"2026-09-01T02:04:53.472253Z","steps":["trace[1781106534] 'agreement among raft nodes before linearized reading' (duration: 119.040601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T02:04:53.472595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.859756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T02:04:53.472657Z","caller":"traceutil/trace.go:171","msg":"trace[1815368610] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5169; }","duration":"198.935487ms","start":"2026-09-01T02:04:53.273706Z","end":"2026-09-01T02:04:53.472641Z","steps":["trace[1815368610] 'agreement among raft nodes before linearized reading' (duration: 198.254716ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:53.842548Z","caller":"traceutil/trace.go:171","msg":"trace[1660127242] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"133.194102ms","start":"2026-09-01T02:04:53.709323Z","end":"2026-09-01T02:04:53.842517Z","steps":["trace[1660127242] 'process raft request' (duration: 132.944037ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:04:53.895953Z","caller":"traceutil/trace.go:171","msg":"trace[806931344] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"118.621954ms","start":"2026-09-01T02:04:53.777304Z","end":"2026-09-01T02:04:53.895926Z","steps":["trace[806931344] 'process raft request' (duration: 83.447241ms)","trace[806931344] 'compare' (duration: 35.00912ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T02:04:53.897302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.141846ms","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-01T02:04:53.897437Z","caller":"traceutil/trace.go:171","msg":"trace[10100909] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5175; }","duration":"122.34311ms","start":"2026-09-01T02:04:53.775065Z","end":"2026-09-01T02:04:53.897408Z","steps":["trace[10100909] 'agreement among raft nodes before linearized reading' (duration: 121.2279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T02:04:53.898069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.300787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-01T02:04:53.898173Z","caller":"traceutil/trace.go:171","msg":"trace[892741094] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5175; }","duration":"123.47894ms","start":"2026-09-01T02:04:53.77467Z","end":"2026-09-01T02:04:53.898149Z","steps":["trace[892741094] 'agreement among raft nodes before linearized reading' (duration: 121.459025ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:05:14.861282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2851} {"level":"info","ts":"2026-09-01T02:05:14.949072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2851,"took":"86.168047ms","hash":2922118056,"current-db-size-bytes":52658176,"current-db-size":"53 MB","current-db-size-in-use-bytes":28008448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T02:05:14.949169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2922118056,"revision":2851,"compact-revision":-1} {"level":"info","ts":"2026-09-01T02:05:38.662923Z","caller":"traceutil/trace.go:171","msg":"trace[1459578543] linearizableReadLoop","detail":"{readStateIndex:5762; appliedIndex:5760; }","duration":"102.019123ms","start":"2026-09-01T02:05:38.560875Z","end":"2026-09-01T02:05:38.662894Z","steps":["trace[1459578543] 'read index received' (duration: 61.275209ms)","trace[1459578543] 'applied index is now lower than readState.Index' (duration: 40.742534ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T02:05:38.663422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.519221ms","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-01T02:05:38.663456Z","caller":"traceutil/trace.go:171","msg":"trace[1173692725] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"106.849317ms","start":"2026-09-01T02:05:38.556554Z","end":"2026-09-01T02:05:38.663404Z","steps":["trace[1173692725] 'process raft request' (duration: 106.178025ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:05:38.663532Z","caller":"traceutil/trace.go:171","msg":"trace[1924124137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5556; }","duration":"102.681765ms","start":"2026-09-01T02:05:38.560828Z","end":"2026-09-01T02:05:38.66351Z","steps":["trace[1924124137] 'agreement among raft nodes before linearized reading' (duration: 102.308128ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:08:28.941151Z","caller":"traceutil/trace.go:171","msg":"trace[318789048] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"154.56924ms","start":"2026-09-01T02:08:28.786554Z","end":"2026-09-01T02:08:28.941124Z","steps":["trace[318789048] 'process raft request' (duration: 144.498165ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T02:10:14.868442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5328} {"level":"info","ts":"2026-09-01T02:10:14.95176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5328,"took":"81.567222ms","hash":627874117,"current-db-size-bytes":52658176,"current-db-size":"53 MB","current-db-size-in-use-bytes":18755584,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-01T02:10:14.951837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":627874117,"revision":5328,"compact-revision":2851}