{"level":"warn","ts":"2026-09-09T06:08:16.047816Z","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-09T06:08:16.04788Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.17: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.17:2380","--initial-cluster=instance=https://162.253.55.17:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.17:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.17: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-09T06:08:16.047927Z","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-09T06:08:16.047935Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.17:2380"]} {"level":"info","ts":"2026-09-09T06:08:16.047953Z","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-09T06:08:16.048456Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"]} {"level":"info","ts":"2026-09-09T06:08:16.048536Z","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.17:2380"],"listen-peer-urls":["https://162.253.55.17:2380"],"advertise-client-urls":["https://162.253.55.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.17: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-09T06:08:16.052793Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.099046ms"} {"level":"info","ts":"2026-09-09T06:08:16.058561Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9d6ea9653fb28c79","cluster-id":"57f89a414bd43f68"} {"level":"info","ts":"2026-09-09T06:08:16.058609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:08:16.058621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:08:16.05863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9d6ea9653fb28c79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:08:16.058636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:08:16.058663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=(11344190763718315129)"} {"level":"warn","ts":"2026-09-09T06:08:16.065366Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:08:16.066573Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:08:16.067721Z","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-09T06:08:16.069454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9d6ea9653fb28c79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:08:16.072419Z","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-09T06:08:16.072829Z","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-09T06:08:16.072994Z","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-09T06:08:16.073013Z","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-09T06:08:16.073786Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9d6ea9653fb28c79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:08:16.073844Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.17:2380"} {"level":"info","ts":"2026-09-09T06:08:16.073857Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.17:2380"} {"level":"info","ts":"2026-09-09T06:08:16.073924Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9d6ea9653fb28c79","initial-advertise-peer-urls":["https://162.253.55.17:2380"],"listen-peer-urls":["https://162.253.55.17:2380"],"advertise-client-urls":["https://162.253.55.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:08:16.073945Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:08:16.074922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=(11344190763718315129)"} {"level":"info","ts":"2026-09-09T06:08:16.076198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57f89a414bd43f68","local-member-id":"9d6ea9653fb28c79","added-peer-id":"9d6ea9653fb28c79","added-peer-peer-urls":["https://162.253.55.17:2380"]} {"level":"info","ts":"2026-09-09T06:08:16.959755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:08:16.95981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:08:16.959835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 received MsgPreVoteResp from 9d6ea9653fb28c79 at term 1"} {"level":"info","ts":"2026-09-09T06:08:16.959853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:08:16.959864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 received MsgVoteResp from 9d6ea9653fb28c79 at term 2"} {"level":"info","ts":"2026-09-09T06:08:16.959876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:08:16.959888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9d6ea9653fb28c79 elected leader 9d6ea9653fb28c79 at term 2"} {"level":"info","ts":"2026-09-09T06:08:16.960627Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:08:16.961671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:08:16.961668Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9d6ea9653fb28c79","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.17:2379]}","request-path":"/0/members/9d6ea9653fb28c79/attributes","cluster-id":"57f89a414bd43f68","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:08:16.961712Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:08:16.962067Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:08:16.962108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:08:16.962999Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57f89a414bd43f68","local-member-id":"9d6ea9653fb28c79","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:08:16.963215Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:08:16.963285Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:08:16.963746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:08:16.968804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.17:2379"} {"level":"info","ts":"2026-09-09T06:08:55.951506Z","caller":"traceutil/trace.go:171","msg":"trace[730781896] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"280.874018ms","start":"2026-09-09T06:08:55.670596Z","end":"2026-09-09T06:08:55.95147Z","steps":["trace[730781896] 'process raft request' (duration: 280.65585ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961405Z","caller":"traceutil/trace.go:171","msg":"trace[1183823481] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"111.848994ms","start":"2026-09-09T06:08:55.849533Z","end":"2026-09-09T06:08:55.961382Z","steps":["trace[1183823481] 'process raft request' (duration: 111.792902ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961549Z","caller":"traceutil/trace.go:171","msg":"trace[1187859648] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"266.208952ms","start":"2026-09-09T06:08:55.695333Z","end":"2026-09-09T06:08:55.961542Z","steps":["trace[1187859648] 'process raft request' (duration: 265.746084ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961608Z","caller":"traceutil/trace.go:171","msg":"trace[342019403] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"262.075006ms","start":"2026-09-09T06:08:55.699529Z","end":"2026-09-09T06:08:55.961604Z","steps":["trace[342019403] 'process raft request' (duration: 261.592757ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961651Z","caller":"traceutil/trace.go:171","msg":"trace[1110874841] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"260.60705ms","start":"2026-09-09T06:08:55.701039Z","end":"2026-09-09T06:08:55.961646Z","steps":["trace[1110874841] 'process raft request' (duration: 260.111501ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.96169Z","caller":"traceutil/trace.go:171","msg":"trace[1388514715] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"260.268307ms","start":"2026-09-09T06:08:55.701418Z","end":"2026-09-09T06:08:55.961687Z","steps":["trace[1388514715] 'process raft request' (duration: 259.764287ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961601Z","caller":"traceutil/trace.go:171","msg":"trace[1602840805] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"278.555531ms","start":"2026-09-09T06:08:55.682985Z","end":"2026-09-09T06:08:55.961541Z","steps":["trace[1602840805] 'process raft request' (duration: 278.069442ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.96192Z","caller":"traceutil/trace.go:171","msg":"trace[1876728004] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"287.808762ms","start":"2026-09-09T06:08:55.674078Z","end":"2026-09-09T06:08:55.961887Z","steps":["trace[1876728004] 'process raft request' (duration: 286.779813ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961924Z","caller":"traceutil/trace.go:171","msg":"trace[46303093] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"242.126198ms","start":"2026-09-09T06:08:55.719711Z","end":"2026-09-09T06:08:55.961838Z","steps":["trace[46303093] 'process raft request' (duration: 241.546616ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.961909Z","caller":"traceutil/trace.go:171","msg":"trace[1477404907] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"287.673507ms","start":"2026-09-09T06:08:55.674187Z","end":"2026-09-09T06:08:55.96186Z","steps":["trace[1477404907] 'process raft request' (duration: 286.777793ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.962768Z","caller":"traceutil/trace.go:171","msg":"trace[1860661767] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"279.862081ms","start":"2026-09-09T06:08:55.682898Z","end":"2026-09-09T06:08:55.96276Z","steps":["trace[1860661767] 'process raft request' (duration: 278.097353ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:08:55.966536Z","caller":"traceutil/trace.go:171","msg":"trace[1162121992] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"279.862851ms","start":"2026-09-09T06:08:55.68295Z","end":"2026-09-09T06:08:55.962813Z","steps":["trace[1162121992] 'process raft request' (duration: 278.076562ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:09:02.371364Z","caller":"traceutil/trace.go:171","msg":"trace[490294018] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"368.819639ms","start":"2026-09-09T06:09:02.002514Z","end":"2026-09-09T06:09:02.371334Z","steps":["trace[490294018] 'read index received' (duration: 367.986539ms)","trace[490294018] 'applied index is now lower than readState.Index' (duration: 832µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:09:02.371494Z","caller":"traceutil/trace.go:171","msg":"trace[2145280875] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"405.179879ms","start":"2026-09-09T06:09:01.966285Z","end":"2026-09-09T06:09:02.371464Z","steps":["trace[2145280875] 'process raft request' (duration: 404.267217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:02.371658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.117009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8jpv4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-09T06:09:02.371718Z","caller":"traceutil/trace.go:171","msg":"trace[1964098390] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8jpv4; range_end:; response_count:1; response_revision:637; }","duration":"369.226643ms","start":"2026-09-09T06:09:02.002479Z","end":"2026-09-09T06:09:02.371706Z","steps":["trace[1964098390] 'agreement among raft nodes before linearized reading' (duration: 368.997075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:02.37175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:02.002463Z","time spent":"369.276585ms","remote":"127.0.0.1:48340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-8jpv4\" "} {"level":"warn","ts":"2026-09-09T06:09:02.372306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:01.966265Z","time spent":"405.372996ms","remote":"127.0.0.1:48228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:09:02.856831Z","caller":"traceutil/trace.go:171","msg":"trace[1455145] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"418.866654ms","start":"2026-09-09T06:09:02.437942Z","end":"2026-09-09T06:09:02.856809Z","steps":["trace[1455145] 'read index received' (duration: 418.717319ms)","trace[1455145] 'applied index is now lower than readState.Index' (duration: 148.435µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:09:02.856887Z","caller":"traceutil/trace.go:171","msg":"trace[566193935] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"464.62209ms","start":"2026-09-09T06:09:02.392229Z","end":"2026-09-09T06:09:02.856851Z","steps":["trace[566193935] 'process raft request' (duration: 464.427043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:02.857035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.075431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-09T06:09:02.857035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:02.390002Z","time spent":"466.959181ms","remote":"127.0.0.1:48340","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-09T06:09:02.857067Z","caller":"traceutil/trace.go:171","msg":"trace[1767451157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"419.165615ms","start":"2026-09-09T06:09:02.437892Z","end":"2026-09-09T06:09:02.857058Z","steps":["trace[1767451157] 'agreement among raft nodes before linearized reading' (duration: 419.018499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:02.857099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:02.437872Z","time spent":"419.219277ms","remote":"127.0.0.1:48414","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":"info","ts":"2026-09-09T06:09:02.99838Z","caller":"traceutil/trace.go:171","msg":"trace[1234855265] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"135.746356ms","start":"2026-09-09T06:09:02.862606Z","end":"2026-09-09T06:09:02.998352Z","steps":["trace[1234855265] 'process raft request' (duration: 135.704664ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:09:02.99883Z","caller":"traceutil/trace.go:171","msg":"trace[678953379] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"548.748353ms","start":"2026-09-09T06:09:02.450057Z","end":"2026-09-09T06:09:02.998806Z","steps":["trace[678953379] 'process raft request' (duration: 542.760972ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:09:02.998856Z","caller":"traceutil/trace.go:171","msg":"trace[1206684151] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"172.210085ms","start":"2026-09-09T06:09:02.826626Z","end":"2026-09-09T06:09:02.998836Z","steps":["trace[1206684151] 'process raft request' (duration: 171.615544ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:09:02.998943Z","caller":"traceutil/trace.go:171","msg":"trace[973057724] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"142.024716ms","start":"2026-09-09T06:09:02.856891Z","end":"2026-09-09T06:09:02.998916Z","steps":["trace[973057724] 'read index received' (duration: 135.909081ms)","trace[973057724] 'applied index is now lower than readState.Index' (duration: 6.086504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:09:02.99911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:02.450033Z","time spent":"548.889548ms","remote":"127.0.0.1:48644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T06:09:02.999293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.666043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T06:09:02.999334Z","caller":"traceutil/trace.go:171","msg":"trace[626717192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"374.787307ms","start":"2026-09-09T06:09:02.624536Z","end":"2026-09-09T06:09:02.999323Z","steps":["trace[626717192] 'agreement among raft nodes before linearized reading' (duration: 374.493827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:02.999393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:02.624523Z","time spent":"374.859129ms","remote":"127.0.0.1:48414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:09:19.436055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.733604ms","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-09T06:09:19.436209Z","caller":"traceutil/trace.go:171","msg":"trace[1080580452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"343.977301ms","start":"2026-09-09T06:09:19.092205Z","end":"2026-09-09T06:09:19.436182Z","steps":["trace[1080580452] 'range keys from in-memory index tree' (duration: 343.398774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:19.436165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.437649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4h6f9\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-09-09T06:09:19.436282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:19.092177Z","time spent":"344.085405ms","remote":"127.0.0.1:48414","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-09T06:09:19.436297Z","caller":"traceutil/trace.go:171","msg":"trace[689771827] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4h6f9; range_end:; response_count:1; response_revision:803; }","duration":"322.670546ms","start":"2026-09-09T06:09:19.113598Z","end":"2026-09-09T06:09:19.436269Z","steps":["trace[689771827] 'range keys from in-memory index tree' (duration: 322.061078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:19.436361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:19.113555Z","time spent":"322.793611ms","remote":"127.0.0.1:48340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4h6f9\" "} {"level":"warn","ts":"2026-09-09T06:09:19.436194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.843797ms","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-09T06:09:19.436756Z","caller":"traceutil/trace.go:171","msg":"trace[864292865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"344.482256ms","start":"2026-09-09T06:09:19.092249Z","end":"2026-09-09T06:09:19.436732Z","steps":["trace[864292865] 'range keys from in-memory index tree' (duration: 343.626141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:19.437121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:09:19.092222Z","time spent":"344.663632ms","remote":"127.0.0.1:48414","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":"info","ts":"2026-09-09T06:09:51.55274Z","caller":"traceutil/trace.go:171","msg":"trace[132914769] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"249.694497ms","start":"2026-09-09T06:09:51.303027Z","end":"2026-09-09T06:09:51.552722Z","steps":["trace[132914769] 'read index received' (duration: 249.517642ms)","trace[132914769] 'applied index is now lower than readState.Index' (duration: 176.045µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:09:51.552805Z","caller":"traceutil/trace.go:171","msg":"trace[957306764] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"273.470139ms","start":"2026-09-09T06:09:51.279303Z","end":"2026-09-09T06:09:51.552773Z","steps":["trace[957306764] 'process raft request' (duration: 273.308825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:51.552842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.810589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:09:51.553026Z","caller":"traceutil/trace.go:171","msg":"trace[829945750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"250.003693ms","start":"2026-09-09T06:09:51.302961Z","end":"2026-09-09T06:09:51.552964Z","steps":["trace[829945750] 'agreement among raft nodes before linearized reading' (duration: 249.834329ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:51.9146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.896204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-09T06:09:51.914723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.473518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T06:09:51.914775Z","caller":"traceutil/trace.go:171","msg":"trace[419741101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"222.56743ms","start":"2026-09-09T06:09:51.692191Z","end":"2026-09-09T06:09:51.914758Z","steps":["trace[419741101] 'range keys from in-memory index tree' (duration: 222.340805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:09:51.9146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.02591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T06:09:51.914749Z","caller":"traceutil/trace.go:171","msg":"trace[774942513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"221.071017ms","start":"2026-09-09T06:09:51.693657Z","end":"2026-09-09T06:09:51.914728Z","steps":["trace[774942513] 'range keys from in-memory index tree' (duration: 220.782901ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:09:51.914848Z","caller":"traceutil/trace.go:171","msg":"trace[612334621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"253.311296ms","start":"2026-09-09T06:09:51.661518Z","end":"2026-09-09T06:09:51.914829Z","steps":["trace[612334621] 'range keys from in-memory index tree' (duration: 252.833426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:38.469166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.545173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:10:38.46926Z","caller":"traceutil/trace.go:171","msg":"trace[626356675] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1876; }","duration":"159.702536ms","start":"2026-09-09T06:10:38.309537Z","end":"2026-09-09T06:10:38.46924Z","steps":["trace[626356675] 'count revisions from in-memory index tree' (duration: 159.392642ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:10:38.869385Z","caller":"traceutil/trace.go:171","msg":"trace[1114370270] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"455.00133ms","start":"2026-09-09T06:10:38.414357Z","end":"2026-09-09T06:10:38.869358Z","steps":["trace[1114370270] 'process raft request' (duration: 454.817707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:38.86964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:10:38.414328Z","time spent":"455.225954ms","remote":"127.0.0.1:48414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:10:38.869796Z","caller":"traceutil/trace.go:171","msg":"trace[904301385] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1949; }","duration":"351.051431ms","start":"2026-09-09T06:10:38.5187Z","end":"2026-09-09T06:10:38.869751Z","steps":["trace[904301385] 'read index received' (duration: 351.045191ms)","trace[904301385] 'applied index is now lower than readState.Index' (duration: 5.1µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:10:38.870017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.205061ms","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-09T06:10:38.870067Z","caller":"traceutil/trace.go:171","msg":"trace[1838363505] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1877; }","duration":"237.293612ms","start":"2026-09-09T06:10:38.632755Z","end":"2026-09-09T06:10:38.870049Z","steps":["trace[1838363505] 'agreement among raft nodes before linearized reading' (duration: 237.15073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:38.870148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.425477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a47a99a-5665-46ed-9f94-c2bcdc08f1e4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-09T06:10:38.870285Z","caller":"traceutil/trace.go:171","msg":"trace[1019708157] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a47a99a-5665-46ed-9f94-c2bcdc08f1e4; range_end:; response_count:1; response_revision:1877; }","duration":"351.58681ms","start":"2026-09-09T06:10:38.518651Z","end":"2026-09-09T06:10:38.870238Z","steps":["trace[1019708157] 'agreement among raft nodes before linearized reading' (duration: 351.196823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:10:38.870355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:10:38.518628Z","time spent":"351.714132ms","remote":"127.0.0.1:48340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a47a99a-5665-46ed-9f94-c2bcdc08f1e4\" "} {"level":"info","ts":"2026-09-09T06:10:39.019709Z","caller":"traceutil/trace.go:171","msg":"trace[57823212] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"141.976849ms","start":"2026-09-09T06:10:38.877708Z","end":"2026-09-09T06:10:39.019685Z","steps":["trace[57823212] 'process raft request' (duration: 141.819807ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:10:51.941439Z","caller":"traceutil/trace.go:171","msg":"trace[638324156] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"153.621884ms","start":"2026-09-09T06:10:51.787789Z","end":"2026-09-09T06:10:51.94141Z","steps":["trace[638324156] 'process raft request' (duration: 153.473772ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:11:07.300348Z","caller":"traceutil/trace.go:171","msg":"trace[1100709415] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"117.461395ms","start":"2026-09-09T06:11:07.182844Z","end":"2026-09-09T06:11:07.300305Z","steps":["trace[1100709415] 'process raft request' (duration: 117.231462ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:11:13.477472Z","caller":"traceutil/trace.go:171","msg":"trace[1884616455] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"286.590011ms","start":"2026-09-09T06:11:13.190857Z","end":"2026-09-09T06:11:13.477447Z","steps":["trace[1884616455] 'read index received' (duration: 286.445579ms)","trace[1884616455] 'applied index is now lower than readState.Index' (duration: 141.252µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:11:13.477512Z","caller":"traceutil/trace.go:171","msg":"trace[10832167] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"346.68442ms","start":"2026-09-09T06:11:13.130803Z","end":"2026-09-09T06:11:13.477487Z","steps":["trace[10832167] 'process raft request' (duration: 346.453797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:11:13.477719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.764319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:11:13.477749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.887725ms","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-09T06:11:13.477795Z","caller":"traceutil/trace.go:171","msg":"trace[115497582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2123; }","duration":"174.885662ms","start":"2026-09-09T06:11:13.302897Z","end":"2026-09-09T06:11:13.477783Z","steps":["trace[115497582] 'agreement among raft nodes before linearized reading' (duration: 174.74058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:11:13.47764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:11:13.130778Z","time spent":"346.791961ms","remote":"127.0.0.1:48210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:11:13.477823Z","caller":"traceutil/trace.go:171","msg":"trace[1369432351] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2123; }","duration":"286.997627ms","start":"2026-09-09T06:11:13.19081Z","end":"2026-09-09T06:11:13.477807Z","steps":["trace[1369432351] 'agreement among raft nodes before linearized reading' (duration: 286.764174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:11:13.477912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.114923ms","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-09T06:11:13.478Z","caller":"traceutil/trace.go:171","msg":"trace[1955921079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2123; }","duration":"151.229015ms","start":"2026-09-09T06:11:13.326748Z","end":"2026-09-09T06:11:13.477977Z","steps":["trace[1955921079] 'agreement among raft nodes before linearized reading' (duration: 150.964932ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:11:13.654051Z","caller":"traceutil/trace.go:171","msg":"trace[49125751] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"171.765718ms","start":"2026-09-09T06:11:13.482256Z","end":"2026-09-09T06:11:13.654021Z","steps":["trace[49125751] 'process raft request' (duration: 119.60033ms)","trace[49125751] 'compare' (duration: 51.752863ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:11:13.654709Z","caller":"traceutil/trace.go:171","msg":"trace[593848933] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"171.845958ms","start":"2026-09-09T06:11:13.482848Z","end":"2026-09-09T06:11:13.654694Z","steps":["trace[593848933] 'process raft request' (duration: 171.074668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:11:13.937687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.479333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-09T06:11:13.937802Z","caller":"traceutil/trace.go:171","msg":"trace[1024987336] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2125; }","duration":"118.637144ms","start":"2026-09-09T06:11:13.819113Z","end":"2026-09-09T06:11:13.93775Z","steps":["trace[1024987336] 'range keys from in-memory index tree' (duration: 118.2641ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:20.96459Z","caller":"traceutil/trace.go:171","msg":"trace[1687511645] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"241.117958ms","start":"2026-09-09T06:12:20.723448Z","end":"2026-09-09T06:12:20.964566Z","steps":["trace[1687511645] 'process raft request' (duration: 240.984295ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:20.966327Z","caller":"traceutil/trace.go:171","msg":"trace[254740392] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2683; }","duration":"223.691412ms","start":"2026-09-09T06:12:20.742606Z","end":"2026-09-09T06:12:20.966298Z","steps":["trace[254740392] 'read index received' (duration: 221.86552ms)","trace[254740392] 'applied index is now lower than readState.Index' (duration: 1.824962ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:12:20.966431Z","caller":"traceutil/trace.go:171","msg":"trace[1822411728] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"240.136376ms","start":"2026-09-09T06:12:20.726236Z","end":"2026-09-09T06:12:20.966372Z","steps":["trace[1822411728] 'process raft request' (duration: 239.934703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:12:20.96653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.904034ms","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-09T06:12:20.966591Z","caller":"traceutil/trace.go:171","msg":"trace[1567104722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2590; }","duration":"224.025225ms","start":"2026-09-09T06:12:20.742552Z","end":"2026-09-09T06:12:20.966577Z","steps":["trace[1567104722] 'agreement among raft nodes before linearized reading' (duration: 223.833293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:12:20.966594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.058024ms","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-09T06:12:20.966637Z","caller":"traceutil/trace.go:171","msg":"trace[1200726401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2590; }","duration":"223.151834ms","start":"2026-09-09T06:12:20.743474Z","end":"2026-09-09T06:12:20.966626Z","steps":["trace[1200726401] 'agreement among raft nodes before linearized reading' (duration: 222.970372ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:57.415986Z","caller":"traceutil/trace.go:171","msg":"trace[115436479] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"211.601196ms","start":"2026-09-09T06:12:57.204359Z","end":"2026-09-09T06:12:57.41596Z","steps":["trace[115436479] 'process raft request' (duration: 211.476789ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:12:57.416245Z","caller":"traceutil/trace.go:171","msg":"trace[372832403] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"218.010091ms","start":"2026-09-09T06:12:57.19822Z","end":"2026-09-09T06:12:57.416231Z","steps":["trace[372832403] 'process raft request' (duration: 148.376317ms)","trace[372832403] 'compare' (duration: 68.991969ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:12:57.416498Z","caller":"traceutil/trace.go:171","msg":"trace[642239236] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"218.243344ms","start":"2026-09-09T06:12:57.198221Z","end":"2026-09-09T06:12:57.416464Z","steps":["trace[642239236] 'process raft request' (duration: 217.511504ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:17.941289Z","caller":"traceutil/trace.go:171","msg":"trace[85520456] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"103.475031ms","start":"2026-09-09T06:17:17.837793Z","end":"2026-09-09T06:17:17.941268Z","steps":["trace[85520456] 'process raft request' (duration: 57.298307ms)","trace[85520456] 'compare' (duration: 46.068992ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:18.977482Z","caller":"traceutil/trace.go:171","msg":"trace[67864896] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"134.769738ms","start":"2026-09-09T06:17:18.842691Z","end":"2026-09-09T06:17:18.977461Z","steps":["trace[67864896] 'process raft request' (duration: 134.286068ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:26.752314Z","caller":"traceutil/trace.go:171","msg":"trace[211757264] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"105.272252ms","start":"2026-09-09T06:17:26.647016Z","end":"2026-09-09T06:17:26.752288Z","steps":["trace[211757264] 'process raft request' (duration: 105.102519ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:38.857873Z","caller":"traceutil/trace.go:171","msg":"trace[1236699671] linearizableReadLoop","detail":"{readStateIndex:5323; appliedIndex:5322; }","duration":"154.390768ms","start":"2026-09-09T06:17:38.703463Z","end":"2026-09-09T06:17:38.857854Z","steps":["trace[1236699671] 'read index received' (duration: 154.175334ms)","trace[1236699671] 'applied index is now lower than readState.Index' (duration: 214.614µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:38.85798Z","caller":"traceutil/trace.go:171","msg":"trace[1754903865] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"214.524348ms","start":"2026-09-09T06:17:38.643435Z","end":"2026-09-09T06:17:38.857959Z","steps":["trace[1754903865] 'process raft request' (duration: 214.263693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:38.858071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.600842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-09T06:17:38.858105Z","caller":"traceutil/trace.go:171","msg":"trace[1888414324] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5132; }","duration":"154.681824ms","start":"2026-09-09T06:17:38.703412Z","end":"2026-09-09T06:17:38.858093Z","steps":["trace[1888414324] 'agreement among raft nodes before linearized reading' (duration: 154.547051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:39.021291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.49039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-00944fa7-123f-4f46-baab-9dc6475a1101\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-09T06:17:39.0214Z","caller":"traceutil/trace.go:171","msg":"trace[476987143] range","detail":"{range_begin:/registry/persistentvolumes/pvc-00944fa7-123f-4f46-baab-9dc6475a1101; range_end:; response_count:1; response_revision:5133; }","duration":"157.655013ms","start":"2026-09-09T06:17:38.863713Z","end":"2026-09-09T06:17:39.021368Z","steps":["trace[476987143] 'agreement among raft nodes before linearized reading' (duration: 93.968411ms)","trace[476987143] 'range keys from in-memory index tree' (duration: 63.297794ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:39.069559Z","caller":"traceutil/trace.go:171","msg":"trace[816557740] linearizableReadLoop","detail":"{readStateIndex:5329; appliedIndex:5324; }","duration":"111.903551ms","start":"2026-09-09T06:17:38.957631Z","end":"2026-09-09T06:17:39.069535Z","steps":["trace[816557740] 'read index received' (duration: 111.535424ms)","trace[816557740] 'applied index is now lower than readState.Index' (duration: 367.137µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:39.069705Z","caller":"traceutil/trace.go:171","msg":"trace[3532823] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"200.449013ms","start":"2026-09-09T06:17:38.869244Z","end":"2026-09-09T06:17:39.069693Z","steps":["trace[3532823] 'process raft request' (duration: 200.156567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:39.069873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.645427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17207"} {"level":"info","ts":"2026-09-09T06:17:39.069884Z","caller":"traceutil/trace.go:171","msg":"trace[1846893769] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"205.917363ms","start":"2026-09-09T06:17:38.863932Z","end":"2026-09-09T06:17:39.06985Z","steps":["trace[1846893769] 'process raft request' (duration: 205.25628ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:39.069948Z","caller":"traceutil/trace.go:171","msg":"trace[1140207307] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5138; }","duration":"200.729549ms","start":"2026-09-09T06:17:38.869201Z","end":"2026-09-09T06:17:39.069931Z","steps":["trace[1140207307] 'agreement among raft nodes before linearized reading' (duration: 200.501074ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:39.070094Z","caller":"traceutil/trace.go:171","msg":"trace[1131368598] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"201.059054ms","start":"2026-09-09T06:17:38.869008Z","end":"2026-09-09T06:17:39.070068Z","steps":["trace[1131368598] 'process raft request' (duration: 200.33978ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:39.070239Z","caller":"traceutil/trace.go:171","msg":"trace[1227666796] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"200.31017ms","start":"2026-09-09T06:17:38.869916Z","end":"2026-09-09T06:17:39.070226Z","steps":["trace[1227666796] 'process raft request' (duration: 199.575096ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:39.070297Z","caller":"traceutil/trace.go:171","msg":"trace[456169176] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"200.975424ms","start":"2026-09-09T06:17:38.869304Z","end":"2026-09-09T06:17:39.070279Z","steps":["trace[456169176] 'process raft request' (duration: 200.149757ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:39.257553Z","caller":"traceutil/trace.go:171","msg":"trace[1247476204] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5330; }","duration":"148.629617ms","start":"2026-09-09T06:17:39.108899Z","end":"2026-09-09T06:17:39.257529Z","steps":["trace[1247476204] 'read index received' (duration: 97.080931ms)","trace[1247476204] 'applied index is now lower than readState.Index' (duration: 51.547696ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:39.257943Z","caller":"traceutil/trace.go:171","msg":"trace[777447387] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"168.774593ms","start":"2026-09-09T06:17:39.089135Z","end":"2026-09-09T06:17:39.25791Z","steps":["trace[777447387] 'process raft request' (duration: 116.740246ms)","trace[777447387] 'compare' (duration: 51.553326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:39.258093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.969624ms","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-09T06:17:39.258162Z","caller":"traceutil/trace.go:171","msg":"trace[1570379243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5140; }","duration":"149.278151ms","start":"2026-09-09T06:17:39.108843Z","end":"2026-09-09T06:17:39.258121Z","steps":["trace[1570379243] 'agreement among raft nodes before linearized reading' (duration: 148.76844ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:39.471805Z","caller":"traceutil/trace.go:171","msg":"trace[2016397694] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"127.954261ms","start":"2026-09-09T06:17:39.343823Z","end":"2026-09-09T06:17:39.471777Z","steps":["trace[2016397694] 'process raft request' (duration: 99.355286ms)","trace[2016397694] 'compare' (duration: 28.35655ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:18:16.99788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2893} {"level":"info","ts":"2026-09-09T06:18:17.081343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2893,"took":"81.9567ms","hash":785057022,"current-db-size-bytes":53252096,"current-db-size":"53 MB","current-db-size-in-use-bytes":28413952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:18:17.081431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":785057022,"revision":2893,"compact-revision":-1} {"level":"warn","ts":"2026-09-09T06:18:18.560027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.6836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:18:18.56028Z","caller":"traceutil/trace.go:171","msg":"trace[1919052721] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"207.751518ms","start":"2026-09-09T06:18:18.352506Z","end":"2026-09-09T06:18:18.560258Z","steps":["trace[1919052721] 'process raft request' (duration: 103.63743ms)","trace[1919052721] 'compare' (duration: 103.555238ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:17.004912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5475} {"level":"info","ts":"2026-09-09T06:23:17.085006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5475,"took":"78.581945ms","hash":1583424068,"current-db-size-bytes":53252096,"current-db-size":"53 MB","current-db-size-in-use-bytes":18436096,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-09T06:23:17.085149Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1583424068,"revision":5475,"compact-revision":2893}