{"level":"warn","ts":"2026-10-02T04:45:39.306341Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T04:45:39.306438Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.73: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.73:2380","--initial-cluster=instance=https://162.253.55.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.73:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T04:45:39.306502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T04:45:39.306514Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.73:2380"]} {"level":"info","ts":"2026-10-02T04:45:39.306534Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T04:45:39.307468Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"]} {"level":"info","ts":"2026-10-02T04:45:39.307555Z","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.73:2380"],"listen-peer-urls":["https://162.253.55.73:2380"],"advertise-client-urls":["https://162.253.55.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.73:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T04:45:39.311271Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.532749ms"} {"level":"info","ts":"2026-10-02T04:45:39.321472Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c43c9f84c6588d4","cluster-id":"49c90045dbd991f3"} {"level":"info","ts":"2026-10-02T04:45:39.321542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T04:45:39.321966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became follower at term 0"} {"level":"info","ts":"2026-10-02T04:45:39.322024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c43c9f84c6588d4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T04:45:39.322031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became follower at term 1"} {"level":"info","ts":"2026-10-02T04:45:39.322102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=(5495458038592932052)"} {"level":"warn","ts":"2026-10-02T04:45:39.333762Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T04:45:39.336527Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T04:45:39.338255Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T04:45:39.34058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c43c9f84c6588d4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T04:45:39.340808Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T04:45:39.340926Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T04:45:39.340942Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T04:45:39.341049Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c43c9f84c6588d4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T04:45:39.341597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=(5495458038592932052)"} {"level":"info","ts":"2026-10-02T04:45:39.342614Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49c90045dbd991f3","local-member-id":"4c43c9f84c6588d4","added-peer-id":"4c43c9f84c6588d4","added-peer-peer-urls":["https://162.253.55.73:2380"]} {"level":"info","ts":"2026-10-02T04:45:39.348809Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T04:45:39.348966Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.73:2380"} {"level":"info","ts":"2026-10-02T04:45:39.349078Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.73:2380"} {"level":"info","ts":"2026-10-02T04:45:39.349203Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c43c9f84c6588d4","initial-advertise-peer-urls":["https://162.253.55.73:2380"],"listen-peer-urls":["https://162.253.55.73:2380"],"advertise-client-urls":["https://162.253.55.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T04:45:39.349249Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T04:45:39.725652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T04:45:39.725728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T04:45:39.725785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 received MsgPreVoteResp from 4c43c9f84c6588d4 at term 1"} {"level":"info","ts":"2026-10-02T04:45:39.725882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became candidate at term 2"} {"level":"info","ts":"2026-10-02T04:45:39.725899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 received MsgVoteResp from 4c43c9f84c6588d4 at term 2"} {"level":"info","ts":"2026-10-02T04:45:39.725922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became leader at term 2"} {"level":"info","ts":"2026-10-02T04:45:39.72595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c43c9f84c6588d4 elected leader 4c43c9f84c6588d4 at term 2"} {"level":"info","ts":"2026-10-02T04:45:39.727085Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T04:45:39.727954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T04:45:39.727955Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c43c9f84c6588d4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.73:2379]}","request-path":"/0/members/4c43c9f84c6588d4/attributes","cluster-id":"49c90045dbd991f3","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T04:45:39.727992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T04:45:39.728391Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T04:45:39.728437Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T04:45:39.728475Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49c90045dbd991f3","local-member-id":"4c43c9f84c6588d4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T04:45:39.7286Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T04:45:39.728641Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T04:45:39.731373Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T04:45:39.731933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.73:2379"} {"level":"info","ts":"2026-10-02T04:45:42.593258Z","caller":"traceutil/trace.go:171","msg":"trace[1797274524] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"104.592697ms","start":"2026-10-02T04:45:42.488631Z","end":"2026-10-02T04:45:42.593224Z","steps":["trace[1797274524] 'process raft request' (duration: 19.917269ms)","trace[1797274524] 'compare' (duration: 84.522482ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:45:42.952831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.557501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-02T04:45:42.953156Z","caller":"traceutil/trace.go:171","msg":"trace[858732218] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"354.234895ms","start":"2026-10-02T04:45:42.598696Z","end":"2026-10-02T04:45:42.952931Z","steps":["trace[858732218] 'process raft request' (duration: 149.078611ms)","trace[858732218] 'compare' (duration: 204.394304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:45:42.953272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:45:42.598665Z","time spent":"354.533748ms","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":699,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T04:45:43.394334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.748385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-02T04:45:43.394455Z","caller":"traceutil/trace.go:171","msg":"trace[1192966213] linearizableReadLoop","detail":"{readStateIndex:107; appliedIndex:106; }","duration":"438.330041ms","start":"2026-10-02T04:45:42.956102Z","end":"2026-10-02T04:45:43.394432Z","steps":["trace[1192966213] 'read index received' (duration: 105.342896ms)","trace[1192966213] 'applied index is now lower than readState.Index' (duration: 332.985825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:45:43.39467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.573561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T04:45:43.394699Z","caller":"traceutil/trace.go:171","msg":"trace[332803373] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"753.719366ms","start":"2026-10-02T04:45:42.640844Z","end":"2026-10-02T04:45:43.394563Z","steps":["trace[332803373] 'process raft request' (duration: 420.605476ms)","trace[332803373] 'compare' (duration: 332.563768ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:45:43.39477Z","caller":"traceutil/trace.go:171","msg":"trace[2034761194] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:0; response_revision:104; }","duration":"438.728916ms","start":"2026-10-02T04:45:42.956023Z","end":"2026-10-02T04:45:43.394752Z","steps":["trace[2034761194] 'agreement among raft nodes before linearized reading' (duration: 438.501387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:45:43.394952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:45:42.955975Z","time spent":"438.964666ms","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:kube-scheduler\" "} {"level":"warn","ts":"2026-10-02T04:45:43.395057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:45:42.640769Z","time spent":"754.06553ms","remote":"127.0.0.1:43176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:19.939304Z","caller":"traceutil/trace.go:171","msg":"trace[2020606934] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"223.918462ms","start":"2026-10-02T04:46:19.715361Z","end":"2026-10-02T04:46:19.939279Z","steps":["trace[2020606934] 'process raft request' (duration: 223.627539ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.939699Z","caller":"traceutil/trace.go:171","msg":"trace[446009350] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"224.192835ms","start":"2026-10-02T04:46:19.715463Z","end":"2026-10-02T04:46:19.939656Z","steps":["trace[446009350] 'process raft request' (duration: 223.664651ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.940181Z","caller":"traceutil/trace.go:171","msg":"trace[2144030514] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"206.865981ms","start":"2026-10-02T04:46:19.733299Z","end":"2026-10-02T04:46:19.940165Z","steps":["trace[2144030514] 'process raft request' (duration: 206.61439ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.940286Z","caller":"traceutil/trace.go:171","msg":"trace[1147230486] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"206.84258ms","start":"2026-10-02T04:46:19.733432Z","end":"2026-10-02T04:46:19.940275Z","steps":["trace[1147230486] 'process raft request' (duration: 206.591349ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.940369Z","caller":"traceutil/trace.go:171","msg":"trace[1618157709] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"201.61439ms","start":"2026-10-02T04:46:19.738746Z","end":"2026-10-02T04:46:19.94036Z","steps":["trace[1618157709] 'process raft request' (duration: 201.332147ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.940666Z","caller":"traceutil/trace.go:171","msg":"trace[1517523484] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"201.789548ms","start":"2026-10-02T04:46:19.738855Z","end":"2026-10-02T04:46:19.940645Z","steps":["trace[1517523484] 'process raft request' (duration: 201.265065ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.94089Z","caller":"traceutil/trace.go:171","msg":"trace[506601540] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"197.07009ms","start":"2026-10-02T04:46:19.743811Z","end":"2026-10-02T04:46:19.940881Z","steps":["trace[506601540] 'process raft request' (duration: 196.372039ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.941026Z","caller":"traceutil/trace.go:171","msg":"trace[521634182] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"194.596711ms","start":"2026-10-02T04:46:19.746421Z","end":"2026-10-02T04:46:19.941018Z","steps":["trace[521634182] 'process raft request' (duration: 194.260766ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:19.943112Z","caller":"traceutil/trace.go:171","msg":"trace[542554554] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"192.604083ms","start":"2026-10-02T04:46:19.750486Z","end":"2026-10-02T04:46:19.94309Z","steps":["trace[542554554] 'process raft request' (duration: 190.250689ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.208798Z","caller":"traceutil/trace.go:171","msg":"trace[702038634] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"431.596738ms","start":"2026-10-02T04:46:19.777173Z","end":"2026-10-02T04:46:20.20877Z","steps":["trace[702038634] 'process raft request' (duration: 431.225201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.20922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.777154Z","time spent":"431.713763ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:46:20.209272Z","caller":"traceutil/trace.go:171","msg":"trace[891460880] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"431.797887ms","start":"2026-10-02T04:46:19.777402Z","end":"2026-10-02T04:46:20.2092Z","steps":["trace[891460880] 'process raft request' (duration: 431.301235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.209536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.745906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T04:46:20.209585Z","caller":"traceutil/trace.go:171","msg":"trace[323535470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"397.817129ms","start":"2026-10-02T04:46:19.811758Z","end":"2026-10-02T04:46:20.209575Z","steps":["trace[323535470] 'agreement among raft nodes before linearized reading' (duration: 397.695944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.209626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.811702Z","time spent":"397.914624ms","remote":"127.0.0.1:43236","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-10-02T04:46:20.209904Z","caller":"traceutil/trace.go:171","msg":"trace[1958010918] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"432.317759ms","start":"2026-10-02T04:46:19.777574Z","end":"2026-10-02T04:46:20.209892Z","steps":["trace[1958010918] 'process raft request' (duration: 431.170089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.209817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.777396Z","time spent":"432.083709ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:20.209405Z","caller":"traceutil/trace.go:171","msg":"trace[158751155] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:510; }","duration":"397.614611ms","start":"2026-10-02T04:46:19.811764Z","end":"2026-10-02T04:46:20.209379Z","steps":["trace[158751155] 'read index received' (duration: 127.646742ms)","trace[158751155] 'applied index is now lower than readState.Index' (duration: 269.966998ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:46:20.209955Z","caller":"traceutil/trace.go:171","msg":"trace[1423795347] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"286.236605ms","start":"2026-10-02T04:46:19.923699Z","end":"2026-10-02T04:46:20.209935Z","steps":["trace[1423795347] 'process raft request' (duration: 285.494032ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.210235Z","caller":"traceutil/trace.go:171","msg":"trace[801153796] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"423.967672ms","start":"2026-10-02T04:46:19.786117Z","end":"2026-10-02T04:46:20.210085Z","steps":["trace[801153796] 'process raft request' (duration: 422.725667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.210027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.777552Z","time spent":"432.388202ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T04:46:20.210501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.786101Z","time spent":"424.280016ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:20.418886Z","caller":"traceutil/trace.go:171","msg":"trace[2079630329] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"468.321053ms","start":"2026-10-02T04:46:19.950535Z","end":"2026-10-02T04:46:20.418856Z","steps":["trace[2079630329] 'process raft request' (duration: 459.160969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.41905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.950513Z","time spent":"468.469109ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:20.419167Z","caller":"traceutil/trace.go:171","msg":"trace[566711269] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"468.416097ms","start":"2026-10-02T04:46:19.950726Z","end":"2026-10-02T04:46:20.419142Z","steps":["trace[566711269] 'process raft request' (duration: 468.044031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.419301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.950703Z","time spent":"468.519752ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:46:20.419311Z","caller":"traceutil/trace.go:171","msg":"trace[1229159052] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"468.72126ms","start":"2026-10-02T04:46:19.950556Z","end":"2026-10-02T04:46:20.419277Z","steps":["trace[1229159052] 'process raft request' (duration: 468.173506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.419443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.950535Z","time spent":"468.843746ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:20.419462Z","caller":"traceutil/trace.go:171","msg":"trace[450296254] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"464.941304ms","start":"2026-10-02T04:46:19.95451Z","end":"2026-10-02T04:46:20.419451Z","steps":["trace[450296254] 'process raft request' (duration: 464.381869ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.419443Z","caller":"traceutil/trace.go:171","msg":"trace[262653282] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"464.598049ms","start":"2026-10-02T04:46:19.954821Z","end":"2026-10-02T04:46:20.419419Z","steps":["trace[262653282] 'process raft request' (duration: 464.113307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.419514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.954494Z","time spent":"464.991257ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T04:46:20.41956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.954785Z","time spent":"464.718434ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:20.419735Z","caller":"traceutil/trace.go:171","msg":"trace[686059024] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"464.792387ms","start":"2026-10-02T04:46:19.954931Z","end":"2026-10-02T04:46:20.419723Z","steps":["trace[686059024] 'process raft request' (duration: 464.4151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.419782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.954906Z","time spent":"464.853831ms","remote":"127.0.0.1:43484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T04:46:20.419893Z","caller":"traceutil/trace.go:171","msg":"trace[1708892739] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"453.672138ms","start":"2026-10-02T04:46:19.966204Z","end":"2026-10-02T04:46:20.419875Z","steps":["trace[1708892739] 'process raft request' (duration: 453.375085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.420009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.966191Z","time spent":"453.737531ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:46:20.420057Z","caller":"traceutil/trace.go:171","msg":"trace[730761800] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"451.367837ms","start":"2026-10-02T04:46:19.968672Z","end":"2026-10-02T04:46:20.42004Z","steps":["trace[730761800] 'process raft request' (duration: 451.005291ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.420082Z","caller":"traceutil/trace.go:171","msg":"trace[246811472] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"122.15805ms","start":"2026-10-02T04:46:20.297911Z","end":"2026-10-02T04:46:20.420069Z","steps":["trace[246811472] 'process raft request' (duration: 122.123789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.420112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:19.968657Z","time spent":"451.421939ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:46:20.420014Z","caller":"traceutil/trace.go:171","msg":"trace[1848458904] transaction","detail":"{read_only:false; number_of_response:1; response_revision:510; }","duration":"201.595726ms","start":"2026-10-02T04:46:20.218407Z","end":"2026-10-02T04:46:20.420002Z","steps":["trace[1848458904] 'process raft request' (duration: 201.386347ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.420234Z","caller":"traceutil/trace.go:171","msg":"trace[1574195200] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:522; }","duration":"210.114902ms","start":"2026-10-02T04:46:20.210106Z","end":"2026-10-02T04:46:20.420221Z","steps":["trace[1574195200] 'read index received' (duration: 199.604708ms)","trace[1574195200] 'applied index is now lower than readState.Index' (duration: 10.508954ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:46:20.420257Z","caller":"traceutil/trace.go:171","msg":"trace[472940814] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"200.711598ms","start":"2026-10-02T04:46:20.219529Z","end":"2026-10-02T04:46:20.42024Z","steps":["trace[472940814] 'process raft request' (duration: 200.353392ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.420288Z","caller":"traceutil/trace.go:171","msg":"trace[660923965] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"176.515669ms","start":"2026-10-02T04:46:20.24376Z","end":"2026-10-02T04:46:20.420276Z","steps":["trace[660923965] 'process raft request' (duration: 176.169524ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:20.420351Z","caller":"traceutil/trace.go:171","msg":"trace[1778414265] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"201.516763ms","start":"2026-10-02T04:46:20.218823Z","end":"2026-10-02T04:46:20.42034Z","steps":["trace[1778414265] 'process raft request' (duration: 201.005691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.420359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.494663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T04:46:20.42039Z","caller":"traceutil/trace.go:171","msg":"trace[1789927717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"368.578586ms","start":"2026-10-02T04:46:20.051802Z","end":"2026-10-02T04:46:20.420381Z","steps":["trace[1789927717] 'agreement among raft nodes before linearized reading' (duration: 368.454141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:20.420415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:20.051791Z","time spent":"368.618077ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-02T04:46:26.645881Z","caller":"traceutil/trace.go:171","msg":"trace[268845834] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"114.28946ms","start":"2026-10-02T04:46:26.531562Z","end":"2026-10-02T04:46:26.645851Z","steps":["trace[268845834] 'process raft request' (duration: 114.238468ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:26.646019Z","caller":"traceutil/trace.go:171","msg":"trace[748754485] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"122.36354ms","start":"2026-10-02T04:46:26.523625Z","end":"2026-10-02T04:46:26.645988Z","steps":["trace[748754485] 'process raft request' (duration: 33.864011ms)","trace[748754485] 'compare' (duration: 88.185625ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:46:27.188058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.19675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T04:46:27.188237Z","caller":"traceutil/trace.go:171","msg":"trace[1824924581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"410.730123ms","start":"2026-10-02T04:46:26.777458Z","end":"2026-10-02T04:46:27.188188Z","steps":["trace[1824924581] 'range keys from in-memory index tree' (duration: 409.97882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:27.188287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:26.77744Z","time spent":"410.833948ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T04:46:27.189013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.527002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T04:46:27.189591Z","caller":"traceutil/trace.go:171","msg":"trace[498251084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"337.448813ms","start":"2026-10-02T04:46:26.852124Z","end":"2026-10-02T04:46:27.189573Z","steps":["trace[498251084] 'range keys from in-memory index tree' (duration: 336.375055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:27.189637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:26.852071Z","time spent":"337.550938ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-02T04:46:27.519208Z","caller":"traceutil/trace.go:171","msg":"trace[1255896977] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"401.930977ms","start":"2026-10-02T04:46:27.117251Z","end":"2026-10-02T04:46:27.519182Z","steps":["trace[1255896977] 'process raft request' (duration: 401.33281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:27.519368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:27.117206Z","time spent":"402.074753ms","remote":"127.0.0.1:43092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:46:27.544675Z","caller":"traceutil/trace.go:171","msg":"trace[1891142581] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"351.564225ms","start":"2026-10-02T04:46:27.193082Z","end":"2026-10-02T04:46:27.544646Z","steps":["trace[1891142581] 'process raft request' (duration: 351.386207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:27.54484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:27.193038Z","time spent":"351.717952ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:46:27.545011Z","caller":"traceutil/trace.go:171","msg":"trace[1542251232] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"351.340145ms","start":"2026-10-02T04:46:27.19364Z","end":"2026-10-02T04:46:27.544981Z","steps":["trace[1542251232] 'process raft request' (duration: 350.966569ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:27.545178Z","caller":"traceutil/trace.go:171","msg":"trace[1267024424] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"248.659966ms","start":"2026-10-02T04:46:27.296418Z","end":"2026-10-02T04:46:27.545078Z","steps":["trace[1267024424] 'process raft request' (duration: 248.5306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:46:27.545374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:46:27.193628Z","time spent":"351.536114ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T04:46:43.102097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.294133ms","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-10-02T04:46:43.102221Z","caller":"traceutil/trace.go:171","msg":"trace[1145839156] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:762; }","duration":"224.491382ms","start":"2026-10-02T04:46:42.877712Z","end":"2026-10-02T04:46:43.102203Z","steps":["trace[1145839156] 'count revisions from in-memory index tree' (duration: 224.174738ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:46:43.167154Z","caller":"traceutil/trace.go:171","msg":"trace[599486143] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"222.705046ms","start":"2026-10-02T04:46:42.944426Z","end":"2026-10-02T04:46:43.167131Z","steps":["trace[599486143] 'process raft request' (duration: 222.545708ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:47:13.837967Z","caller":"traceutil/trace.go:171","msg":"trace[1659756332] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"101.678562ms","start":"2026-10-02T04:47:13.736243Z","end":"2026-10-02T04:47:13.837921Z","steps":["trace[1659756332] 'read index received' (duration: 101.518165ms)","trace[1659756332] 'applied index is now lower than readState.Index' (duration: 159.307µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:47:13.838093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.83671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T04:47:13.838123Z","caller":"traceutil/trace.go:171","msg":"trace[1295175304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"101.922813ms","start":"2026-10-02T04:47:13.73619Z","end":"2026-10-02T04:47:13.838113Z","steps":["trace[1295175304] 'agreement among raft nodes before linearized reading' (duration: 101.85197ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:47:13.838112Z","caller":"traceutil/trace.go:171","msg":"trace[488185940] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"108.260623ms","start":"2026-10-02T04:47:13.729829Z","end":"2026-10-02T04:47:13.83809Z","steps":["trace[488185940] 'process raft request' (duration: 107.944058ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:47:14.00902Z","caller":"traceutil/trace.go:171","msg":"trace[156177338] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"169.80642ms","start":"2026-10-02T04:47:13.839195Z","end":"2026-10-02T04:47:14.009002Z","steps":["trace[156177338] 'read index received' (duration: 154.443704ms)","trace[156177338] 'applied index is now lower than readState.Index' (duration: 15.361626ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:47:14.009114Z","caller":"traceutil/trace.go:171","msg":"trace[1283906609] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"179.939258ms","start":"2026-10-02T04:47:13.829164Z","end":"2026-10-02T04:47:14.009103Z","steps":["trace[1283906609] 'process raft request' (duration: 164.52757ms)","trace[1283906609] 'compare' (duration: 15.192127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:47:14.009335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.925005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T04:47:14.009386Z","caller":"traceutil/trace.go:171","msg":"trace[41907265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1165; }","duration":"112.009029ms","start":"2026-10-02T04:47:13.897365Z","end":"2026-10-02T04:47:14.009374Z","steps":["trace[41907265] 'agreement among raft nodes before linearized reading' (duration: 111.864333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:47:14.009439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.23769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T04:47:14.009478Z","caller":"traceutil/trace.go:171","msg":"trace[738916926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"170.335145ms","start":"2026-10-02T04:47:13.839133Z","end":"2026-10-02T04:47:14.009468Z","steps":["trace[738916926] 'agreement among raft nodes before linearized reading' (duration: 170.276102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:47:14.009675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.164014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T04:47:14.009741Z","caller":"traceutil/trace.go:171","msg":"trace[951716514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"106.257628ms","start":"2026-10-02T04:47:13.903467Z","end":"2026-10-02T04:47:14.009725Z","steps":["trace[951716514] 'agreement among raft nodes before linearized reading' (duration: 106.036127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.277113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.480163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-02T04:48:06.277205Z","caller":"traceutil/trace.go:171","msg":"trace[1200221004] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf; range_end:; response_count:1; response_revision:1854; }","duration":"434.628531ms","start":"2026-10-02T04:48:05.842557Z","end":"2026-10-02T04:48:06.277185Z","steps":["trace[1200221004] 'range keys from in-memory index tree' (duration: 434.213865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.277245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:05.842537Z","time spent":"434.69912ms","remote":"127.0.0.1:43176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf\" "} {"level":"warn","ts":"2026-10-02T04:48:06.277246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.245559ms","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-10-02T04:48:06.277308Z","caller":"traceutil/trace.go:171","msg":"trace[1260690748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"431.332388ms","start":"2026-10-02T04:48:05.84596Z","end":"2026-10-02T04:48:06.277292Z","steps":["trace[1260690748] 'range keys from in-memory index tree' (duration: 431.08421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.277347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:05.845951Z","time spent":"431.389418ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T04:48:06.277388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.26542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-02T04:48:06.277493Z","caller":"traceutil/trace.go:171","msg":"trace[1904689645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf; range_end:; response_count:1; response_revision:1854; }","duration":"328.462008ms","start":"2026-10-02T04:48:05.949006Z","end":"2026-10-02T04:48:06.277468Z","steps":["trace[1904689645] 'range keys from in-memory index tree' (duration: 327.752643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.277554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:05.948986Z","time spent":"328.556467ms","remote":"127.0.0.1:43176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf\" "} {"level":"info","ts":"2026-10-02T04:48:06.313578Z","caller":"traceutil/trace.go:171","msg":"trace[1760461648] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"265.370451ms","start":"2026-10-02T04:48:06.048174Z","end":"2026-10-02T04:48:06.313545Z","steps":["trace[1760461648] 'process raft request' (duration: 265.129233ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:06.54069Z","caller":"traceutil/trace.go:171","msg":"trace[287527126] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"255.568976ms","start":"2026-10-02T04:48:06.285089Z","end":"2026-10-02T04:48:06.540658Z","steps":["trace[287527126] 'process raft request' (duration: 255.495257ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:06.540754Z","caller":"traceutil/trace.go:171","msg":"trace[1188895852] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"259.259128ms","start":"2026-10-02T04:48:06.281438Z","end":"2026-10-02T04:48:06.540697Z","steps":["trace[1188895852] 'process raft request' (duration: 258.95027ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:06.760883Z","caller":"traceutil/trace.go:171","msg":"trace[371439917] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1927; }","duration":"437.670144ms","start":"2026-10-02T04:48:06.323186Z","end":"2026-10-02T04:48:06.760856Z","steps":["trace[371439917] 'read index received' (duration: 217.281724ms)","trace[371439917] 'applied index is now lower than readState.Index' (duration: 220.38726ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:48:06.761047Z","caller":"traceutil/trace.go:171","msg":"trace[1360473024] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"310.838688ms","start":"2026-10-02T04:48:06.450188Z","end":"2026-10-02T04:48:06.761027Z","steps":["trace[1360473024] 'process raft request' (duration: 310.58664ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:06.761075Z","caller":"traceutil/trace.go:171","msg":"trace[703198540] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"443.671637ms","start":"2026-10-02T04:48:06.317374Z","end":"2026-10-02T04:48:06.761046Z","steps":["trace[703198540] 'process raft request' (duration: 420.550077ms)","trace[703198540] 'compare' (duration: 22.041069ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:48:06.761174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:06.450165Z","time spent":"310.916197ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T04:48:06.761618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.03273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T04:48:06.761692Z","caller":"traceutil/trace.go:171","msg":"trace[277833303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"411.147329ms","start":"2026-10-02T04:48:06.350528Z","end":"2026-10-02T04:48:06.761675Z","steps":["trace[277833303] 'agreement among raft nodes before linearized reading' (duration: 410.925401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.761741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.552617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T04:48:06.761854Z","caller":"traceutil/trace.go:171","msg":"trace[1425508192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1859; }","duration":"438.686256ms","start":"2026-10-02T04:48:06.323152Z","end":"2026-10-02T04:48:06.761838Z","steps":["trace[1425508192] 'agreement among raft nodes before linearized reading' (duration: 438.505758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.761918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.089133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-02T04:48:06.761744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:06.350516Z","time spent":"411.216449ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-02T04:48:06.76197Z","caller":"traceutil/trace.go:171","msg":"trace[158905238] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1859; }","duration":"372.172502ms","start":"2026-10-02T04:48:06.389783Z","end":"2026-10-02T04:48:06.761956Z","steps":["trace[158905238] 'agreement among raft nodes before linearized reading' (duration: 372.050193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.761618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.594063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-10-02T04:48:06.762018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:06.389763Z","time spent":"372.244011ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-02T04:48:06.761813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.771564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T04:48:06.762086Z","caller":"traceutil/trace.go:171","msg":"trace[577738685] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d22cfe2-fe5b-4293-9e9c-dec8e2ee0aaf; range_end:; response_count:1; response_revision:1859; }","duration":"214.069249ms","start":"2026-10-02T04:48:06.547965Z","end":"2026-10-02T04:48:06.762034Z","steps":["trace[577738685] 'agreement among raft nodes before linearized reading' (duration: 213.523693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.761412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:06.317346Z","time spent":"443.797677ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:48:06.762144Z","caller":"traceutil/trace.go:171","msg":"trace[1154786392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1859; }","duration":"309.116921ms","start":"2026-10-02T04:48:06.453006Z","end":"2026-10-02T04:48:06.762123Z","steps":["trace[1154786392] 'agreement among raft nodes before linearized reading' (duration: 308.765184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:06.76197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:06.323137Z","time spent":"438.817875ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T04:48:06.762573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:06.452995Z","time spent":"309.427819ms","remote":"127.0.0.1:37918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-02T04:48:41.075006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.938391ms","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-10-02T04:48:41.075097Z","caller":"traceutil/trace.go:171","msg":"trace[570548124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2091; }","duration":"329.068782ms","start":"2026-10-02T04:48:40.746009Z","end":"2026-10-02T04:48:41.075078Z","steps":["trace[570548124] 'range keys from in-memory index tree' (duration: 328.736341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.075137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:40.745997Z","time spent":"329.130952ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T04:48:41.075514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.12433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T04:48:41.076193Z","caller":"traceutil/trace.go:171","msg":"trace[763721382] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"194.893043ms","start":"2026-10-02T04:48:40.881282Z","end":"2026-10-02T04:48:41.076175Z","steps":["trace[763721382] 'process raft request' (duration: 19.01237ms)","trace[763721382] 'compare' (duration: 174.453967ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:48:41.139997Z","caller":"traceutil/trace.go:171","msg":"trace[1136767452] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"195.545486ms","start":"2026-10-02T04:48:40.944423Z","end":"2026-10-02T04:48:41.139969Z","steps":["trace[1136767452] 'read index received' (duration: 195.395465ms)","trace[1136767452] 'applied index is now lower than readState.Index' (duration: 149.001µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:48:41.1401Z","caller":"traceutil/trace.go:171","msg":"trace[1963850668] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"252.472632ms","start":"2026-10-02T04:48:40.887616Z","end":"2026-10-02T04:48:41.140088Z","steps":["trace[1963850668] 'process raft request' (duration: 252.206851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.140419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.974847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T04:48:41.140577Z","caller":"traceutil/trace.go:171","msg":"trace[863025930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2093; }","duration":"196.197198ms","start":"2026-10-02T04:48:40.944356Z","end":"2026-10-02T04:48:41.140553Z","steps":["trace[863025930] 'agreement among raft nodes before linearized reading' (duration: 195.794916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.444837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.162645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T04:48:41.445563Z","caller":"traceutil/trace.go:171","msg":"trace[208031110] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"300.614904ms","start":"2026-10-02T04:48:41.144931Z","end":"2026-10-02T04:48:41.445545Z","steps":["trace[208031110] 'process raft request' (duration: 300.533403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.445727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:41.144907Z","time spent":"300.769364ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:48:41.445554Z","caller":"traceutil/trace.go:171","msg":"trace[2030462776] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"356.156152ms","start":"2026-10-02T04:48:41.089352Z","end":"2026-10-02T04:48:41.445508Z","steps":["trace[2030462776] 'process raft request' (duration: 155.201244ms)","trace[2030462776] 'compare' (duration: 199.871494ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:48:41.44614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:41.089319Z","time spent":"356.729185ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:48:41.44558Z","caller":"traceutil/trace.go:171","msg":"trace[440319359] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2172; }","duration":"305.502096ms","start":"2026-10-02T04:48:41.140055Z","end":"2026-10-02T04:48:41.445557Z","steps":["trace[440319359] 'read index received' (duration: 104.568687ms)","trace[440319359] 'applied index is now lower than readState.Index' (duration: 200.917689ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:48:41.445771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.152257ms","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-10-02T04:48:41.446423Z","caller":"traceutil/trace.go:171","msg":"trace[1658293325] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2095; }","duration":"459.826659ms","start":"2026-10-02T04:48:40.986564Z","end":"2026-10-02T04:48:41.446391Z","steps":["trace[1658293325] 'agreement among raft nodes before linearized reading' (duration: 459.044476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.446484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.08729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-10-02T04:48:41.446551Z","caller":"traceutil/trace.go:171","msg":"trace[1283461259] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2095; }","duration":"161.188571ms","start":"2026-10-02T04:48:41.28535Z","end":"2026-10-02T04:48:41.446538Z","steps":["trace[1283461259] 'agreement among raft nodes before linearized reading' (duration: 161.04131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.446559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:40.986541Z","time spent":"459.98634ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-02T04:48:41.445952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.40663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T04:48:41.44669Z","caller":"traceutil/trace.go:171","msg":"trace[1628369286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2095; }","duration":"475.185894ms","start":"2026-10-02T04:48:40.971488Z","end":"2026-10-02T04:48:41.446674Z","steps":["trace[1628369286] 'agreement among raft nodes before linearized reading' (duration: 474.132789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.446734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:48:40.971476Z","time spent":"475.243594ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T04:48:41.446768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.873818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123856"} {"level":"info","ts":"2026-10-02T04:48:41.446959Z","caller":"traceutil/trace.go:171","msg":"trace[131881763] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2095; }","duration":"154.053479ms","start":"2026-10-02T04:48:41.292838Z","end":"2026-10-02T04:48:41.446891Z","steps":["trace[131881763] 'agreement among raft nodes before linearized reading' (duration: 153.727157ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:41.635744Z","caller":"traceutil/trace.go:171","msg":"trace[1991441488] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"183.404789ms","start":"2026-10-02T04:48:41.452286Z","end":"2026-10-02T04:48:41.635691Z","steps":["trace[1991441488] 'process raft request' (duration: 113.208966ms)","trace[1991441488] 'compare' (duration: 70.014133ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:48:41.635763Z","caller":"traceutil/trace.go:171","msg":"trace[1195144538] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2174; }","duration":"182.716937ms","start":"2026-10-02T04:48:41.453019Z","end":"2026-10-02T04:48:41.635736Z","steps":["trace[1195144538] 'read index received' (duration: 112.488413ms)","trace[1195144538] 'applied index is now lower than readState.Index' (duration: 70.225654ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:48:41.63582Z","caller":"traceutil/trace.go:171","msg":"trace[1592493667] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"183.49764ms","start":"2026-10-02T04:48:41.452285Z","end":"2026-10-02T04:48:41.635783Z","steps":["trace[1592493667] 'process raft request' (duration: 183.362779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.636254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.206419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-10-02T04:48:41.63638Z","caller":"traceutil/trace.go:171","msg":"trace[1869910910] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2097; }","duration":"183.365699ms","start":"2026-10-02T04:48:41.452995Z","end":"2026-10-02T04:48:41.636361Z","steps":["trace[1869910910] 'agreement among raft nodes before linearized reading' (duration: 182.808237ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:41.638359Z","caller":"traceutil/trace.go:171","msg":"trace[554053599] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"184.476244ms","start":"2026-10-02T04:48:41.453859Z","end":"2026-10-02T04:48:41.638335Z","steps":["trace[554053599] 'process raft request' (duration: 184.286593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:48:41.638391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.300884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123856"} {"level":"info","ts":"2026-10-02T04:48:41.638454Z","caller":"traceutil/trace.go:171","msg":"trace[614066965] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2099; }","duration":"184.345704ms","start":"2026-10-02T04:48:41.454074Z","end":"2026-10-02T04:48:41.638419Z","steps":["trace[614066965] 'agreement among raft nodes before linearized reading' (duration: 184.128313ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:48:41.63836Z","caller":"traceutil/trace.go:171","msg":"trace[301298834] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"185.281128ms","start":"2026-10-02T04:48:41.453053Z","end":"2026-10-02T04:48:41.638334Z","steps":["trace[301298834] 'process raft request' (duration: 185.021047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:49:49.871951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.221156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T04:49:49.871951Z","caller":"traceutil/trace.go:171","msg":"trace[1054344614] linearizableReadLoop","detail":"{readStateIndex:2656; appliedIndex:2655; }","duration":"214.124703ms","start":"2026-10-02T04:49:49.657694Z","end":"2026-10-02T04:49:49.871819Z","steps":["trace[1054344614] 'read index received' (duration: 174.567403ms)","trace[1054344614] 'applied index is now lower than readState.Index' (duration: 39.55554ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:49:49.872146Z","caller":"traceutil/trace.go:171","msg":"trace[633262079] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2561; }","duration":"214.494542ms","start":"2026-10-02T04:49:49.657631Z","end":"2026-10-02T04:49:49.872126Z","steps":["trace[633262079] 'agreement among raft nodes before linearized reading' (duration: 214.209296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:49:49.872362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.178808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T04:49:49.872414Z","caller":"traceutil/trace.go:171","msg":"trace[2058245860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2561; }","duration":"136.29541ms","start":"2026-10-02T04:49:49.736104Z","end":"2026-10-02T04:49:49.872399Z","steps":["trace[2058245860] 'agreement among raft nodes before linearized reading' (duration: 136.197438ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:50:21.17832Z","caller":"traceutil/trace.go:171","msg":"trace[1698112950] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"151.544117ms","start":"2026-10-02T04:50:21.026752Z","end":"2026-10-02T04:50:21.178297Z","steps":["trace[1698112950] 'process raft request' (duration: 151.362512ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:50:21.213849Z","caller":"traceutil/trace.go:171","msg":"trace[811697837] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"133.387853ms","start":"2026-10-02T04:50:21.080439Z","end":"2026-10-02T04:50:21.213827Z","steps":["trace[811697837] 'process raft request' (duration: 133.303271ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:53:45.188618Z","caller":"traceutil/trace.go:171","msg":"trace[1051019269] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"103.148037ms","start":"2026-10-02T04:53:45.08545Z","end":"2026-10-02T04:53:45.188598Z","steps":["trace[1051019269] 'process raft request' (duration: 103.034902ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:53:45.190069Z","caller":"traceutil/trace.go:171","msg":"trace[768838930] transaction","detail":"{read_only:false; response_revision:3895; number_of_response:1; }","duration":"102.418747ms","start":"2026-10-02T04:53:45.087627Z","end":"2026-10-02T04:53:45.190046Z","steps":["trace[768838930] 'process raft request' (duration: 102.282862ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:54:53.152301Z","caller":"traceutil/trace.go:171","msg":"trace[533421333] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"110.564913ms","start":"2026-10-02T04:54:53.041715Z","end":"2026-10-02T04:54:53.15228Z","steps":["trace[533421333] 'read index received' (duration: 110.380816ms)","trace[533421333] 'applied index is now lower than readState.Index' (duration: 183.207µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:54:53.152379Z","caller":"traceutil/trace.go:171","msg":"trace[947800169] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"136.462298ms","start":"2026-10-02T04:54:53.01588Z","end":"2026-10-02T04:54:53.152342Z","steps":["trace[947800169] 'process raft request' (duration: 136.2675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:54:53.152491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.758221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7899c329-01c3-4b79-8a27-463cdd7d5947\" ","response":"range_response_count:1 size:4049"} {"level":"info","ts":"2026-10-02T04:54:53.152523Z","caller":"traceutil/trace.go:171","msg":"trace[441167212] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7899c329-01c3-4b79-8a27-463cdd7d5947; range_end:; response_count:1; response_revision:4808; }","duration":"110.834034ms","start":"2026-10-02T04:54:53.041679Z","end":"2026-10-02T04:54:53.152513Z","steps":["trace[441167212] 'agreement among raft nodes before linearized reading' (duration: 110.685968ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:54:55.126958Z","caller":"traceutil/trace.go:171","msg":"trace[632076314] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5036; }","duration":"126.585446ms","start":"2026-10-02T04:54:55.000327Z","end":"2026-10-02T04:54:55.126912Z","steps":["trace[632076314] 'read index received' (duration: 99.890688ms)","trace[632076314] 'applied index is now lower than readState.Index' (duration: 26.693587ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:54:55.12702Z","caller":"traceutil/trace.go:171","msg":"trace[1720288187] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"136.226661ms","start":"2026-10-02T04:54:54.990749Z","end":"2026-10-02T04:54:55.126976Z","steps":["trace[1720288187] 'process raft request' (duration: 109.481972ms)","trace[1720288187] 'compare' (duration: 26.580052ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:54:55.127142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.800874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-10-02T04:54:55.127393Z","caller":"traceutil/trace.go:171","msg":"trace[1430633125] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4854; }","duration":"127.041364ms","start":"2026-10-02T04:54:55.000321Z","end":"2026-10-02T04:54:55.127362Z","steps":["trace[1430633125] 'agreement among raft nodes before linearized reading' (duration: 126.70413ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:54:55.809291Z","caller":"traceutil/trace.go:171","msg":"trace[1792899946] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"101.65311ms","start":"2026-10-02T04:54:55.707615Z","end":"2026-10-02T04:54:55.809268Z","steps":["trace[1792899946] 'process raft request' (duration: 52.305421ms)","trace[1792899946] 'compare' (duration: 49.002015ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:54:55.809727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.667745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11453"} {"level":"info","ts":"2026-10-02T04:54:55.809806Z","caller":"traceutil/trace.go:171","msg":"trace[1157972379] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4872; }","duration":"105.76768ms","start":"2026-10-02T04:54:55.704019Z","end":"2026-10-02T04:54:55.809786Z","steps":["trace[1157972379] 'agreement among raft nodes before linearized reading' (duration: 105.589532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:54:55.810048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.209967ms","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-10-02T04:54:55.810085Z","caller":"traceutil/trace.go:171","msg":"trace[407905196] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4872; }","duration":"105.26673ms","start":"2026-10-02T04:54:55.704809Z","end":"2026-10-02T04:54:55.810076Z","steps":["trace[407905196] 'agreement among raft nodes before linearized reading' (duration: 105.175686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:54:56.542606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.78353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-4sl8k\" ","response":"range_response_count:1 size:5150"} {"level":"info","ts":"2026-10-02T04:54:56.542728Z","caller":"traceutil/trace.go:171","msg":"trace[230781912] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-4sl8k; range_end:; response_count:1; response_revision:4884; }","duration":"103.956536ms","start":"2026-10-02T04:54:56.438742Z","end":"2026-10-02T04:54:56.542699Z","steps":["trace[230781912] 'range keys from in-memory index tree' (duration: 103.578961ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:02.203651Z","caller":"traceutil/trace.go:171","msg":"trace[968532875] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"259.152825ms","start":"2026-10-02T04:55:01.944472Z","end":"2026-10-02T04:55:02.203625Z","steps":["trace[968532875] 'process raft request' (duration: 228.515332ms)","trace[968532875] 'compare' (duration: 30.518068ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:55:02.74164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.885603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-02T04:55:02.741853Z","caller":"traceutil/trace.go:171","msg":"trace[356262607] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"395.174865ms","start":"2026-10-02T04:55:02.34666Z","end":"2026-10-02T04:55:02.741835Z","steps":["trace[356262607] 'process raft request' (duration: 395.083841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:55:02.741929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:02.346639Z","time spent":"395.247728ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T04:55:02.74212Z","caller":"traceutil/trace.go:171","msg":"trace[1258960436] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"791.476375ms","start":"2026-10-02T04:55:01.950618Z","end":"2026-10-02T04:55:02.742094Z","steps":["trace[1258960436] 'process raft request' (duration: 541.064249ms)","trace[1258960436] 'compare' (duration: 249.728737ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:55:02.74215Z","caller":"traceutil/trace.go:171","msg":"trace[1049105430] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5173; }","duration":"789.974042ms","start":"2026-10-02T04:55:01.952142Z","end":"2026-10-02T04:55:02.742116Z","steps":["trace[1049105430] 'read index received' (duration: 220.831985ms)","trace[1049105430] 'applied index is now lower than readState.Index' (duration: 569.075355ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T04:55:02.742287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:01.950598Z","time spent":"791.587968ms","remote":"127.0.0.1:43076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T04:55:02.742458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.310306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6445cd5c-3965-40a1-bcca-24b63a08ca64\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-10-02T04:55:02.7425Z","caller":"traceutil/trace.go:171","msg":"trace[1138043318] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6445cd5c-3965-40a1-bcca-24b63a08ca64; range_end:; response_count:1; response_revision:4988; }","duration":"790.39108ms","start":"2026-10-02T04:55:01.952096Z","end":"2026-10-02T04:55:02.742487Z","steps":["trace[1138043318] 'agreement among raft nodes before linearized reading' (duration: 790.180491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:55:02.742529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:01.952078Z","time spent":"790.444331ms","remote":"127.0.0.1:43176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6445cd5c-3965-40a1-bcca-24b63a08ca64\" "} {"level":"warn","ts":"2026-10-02T04:55:02.742546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.018218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-57dbb7ddff-46fx6\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-10-02T04:55:02.74262Z","caller":"traceutil/trace.go:171","msg":"trace[1420837428] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-57dbb7ddff-46fx6; range_end:; response_count:1; response_revision:4988; }","duration":"533.097661ms","start":"2026-10-02T04:55:02.2095Z","end":"2026-10-02T04:55:02.742598Z","steps":["trace[1420837428] 'agreement among raft nodes before linearized reading' (duration: 532.804179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:55:02.742665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T04:55:02.209447Z","time spent":"533.205846ms","remote":"127.0.0.1:43176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11278,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-57dbb7ddff-46fx6\" "} {"level":"info","ts":"2026-10-02T04:55:03.00718Z","caller":"traceutil/trace.go:171","msg":"trace[1336579080] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"246.439932ms","start":"2026-10-02T04:55:02.760723Z","end":"2026-10-02T04:55:03.007163Z","steps":["trace[1336579080] 'process raft request' (duration: 246.308887ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:03.007169Z","caller":"traceutil/trace.go:171","msg":"trace[1566236524] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"250.321602ms","start":"2026-10-02T04:55:02.756813Z","end":"2026-10-02T04:55:03.007134Z","steps":["trace[1566236524] 'process raft request' (duration: 194.765651ms)","trace[1566236524] 'compare' (duration: 55.250128ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:55:03.007476Z","caller":"traceutil/trace.go:171","msg":"trace[2084750759] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"195.075544ms","start":"2026-10-02T04:55:02.812391Z","end":"2026-10-02T04:55:03.007466Z","steps":["trace[2084750759] 'process raft request' (duration: 194.74127ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:16.195173Z","caller":"traceutil/trace.go:171","msg":"trace[500797925] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"196.650228ms","start":"2026-10-02T04:55:15.998493Z","end":"2026-10-02T04:55:16.195143Z","steps":["trace[500797925] 'process raft request' (duration: 191.327378ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:16.210802Z","caller":"traceutil/trace.go:171","msg":"trace[386722298] linearizableReadLoop","detail":"{readStateIndex:5363; appliedIndex:5358; }","duration":"124.742149ms","start":"2026-10-02T04:55:16.086035Z","end":"2026-10-02T04:55:16.210777Z","steps":["trace[386722298] 'read index received' (duration: 103.796821ms)","trace[386722298] 'applied index is now lower than readState.Index' (duration: 20.944238ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:55:16.211104Z","caller":"traceutil/trace.go:171","msg":"trace[1777580472] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"212.344419ms","start":"2026-10-02T04:55:15.998744Z","end":"2026-10-02T04:55:16.211089Z","steps":["trace[1777580472] 'process raft request' (duration: 211.796986ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:16.211282Z","caller":"traceutil/trace.go:171","msg":"trace[707562436] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"212.092849ms","start":"2026-10-02T04:55:15.99918Z","end":"2026-10-02T04:55:16.211273Z","steps":["trace[707562436] 'process raft request' (duration: 211.497414ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:16.211419Z","caller":"traceutil/trace.go:171","msg":"trace[1747211757] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"211.472732ms","start":"2026-10-02T04:55:15.999936Z","end":"2026-10-02T04:55:16.211408Z","steps":["trace[1747211757] 'process raft request' (duration: 210.781483ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:16.211518Z","caller":"traceutil/trace.go:171","msg":"trace[2102082421] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"210.960851ms","start":"2026-10-02T04:55:16.000549Z","end":"2026-10-02T04:55:16.21151Z","steps":["trace[2102082421] 'process raft request' (duration: 210.19481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:55:16.211715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.672458ms","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-10-02T04:55:16.21176Z","caller":"traceutil/trace.go:171","msg":"trace[33527454] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5169; }","duration":"125.752581ms","start":"2026-10-02T04:55:16.085983Z","end":"2026-10-02T04:55:16.211736Z","steps":["trace[33527454] 'agreement among raft nodes before linearized reading' (duration: 125.625635ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:16.353047Z","caller":"traceutil/trace.go:171","msg":"trace[693915428] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"131.516469ms","start":"2026-10-02T04:55:16.221491Z","end":"2026-10-02T04:55:16.353007Z","steps":["trace[693915428] 'process raft request' (duration: 92.521524ms)","trace[693915428] 'compare' (duration: 38.779276ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T04:55:16.372182Z","caller":"traceutil/trace.go:171","msg":"trace[141202846] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"142.990345ms","start":"2026-10-02T04:55:16.229163Z","end":"2026-10-02T04:55:16.372153Z","steps":["trace[141202846] 'process raft request' (duration: 142.769066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:55:16.56435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.549976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-57dbb7ddff-46fx6\" ","response":"range_response_count:1 size:11844"} {"level":"info","ts":"2026-10-02T04:55:16.564446Z","caller":"traceutil/trace.go:171","msg":"trace[457216570] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-57dbb7ddff-46fx6; range_end:; response_count:1; response_revision:5175; }","duration":"100.716944ms","start":"2026-10-02T04:55:16.463709Z","end":"2026-10-02T04:55:16.564426Z","steps":["trace[457216570] 'range keys from in-memory index tree' (duration: 100.38356ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:55:39.885673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2837} {"level":"info","ts":"2026-10-02T04:55:39.981923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2837,"took":"94.630128ms","hash":3211560277,"current-db-size-bytes":51519488,"current-db-size":"52 MB","current-db-size-in-use-bytes":27467776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T04:55:39.981988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3211560277,"revision":2837,"compact-revision":-1} {"level":"info","ts":"2026-10-02T04:56:32.549052Z","caller":"traceutil/trace.go:171","msg":"trace[640628860] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"106.497417ms","start":"2026-10-02T04:56:32.442524Z","end":"2026-10-02T04:56:32.549022Z","steps":["trace[640628860] 'process raft request' (duration: 106.305479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T04:58:03.609124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.330524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T04:58:03.609686Z","caller":"traceutil/trace.go:171","msg":"trace[568162079] transaction","detail":"{read_only:false; response_revision:6316; number_of_response:1; }","duration":"223.557364ms","start":"2026-10-02T04:58:03.386099Z","end":"2026-10-02T04:58:03.609656Z","steps":["trace[568162079] 'process raft request' (duration: 223.259391ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:58:03.60986Z","caller":"traceutil/trace.go:171","msg":"trace[2101541551] transaction","detail":"{read_only:false; response_revision:6315; number_of_response:1; }","duration":"248.975133ms","start":"2026-10-02T04:58:03.360674Z","end":"2026-10-02T04:58:03.609649Z","steps":["trace[2101541551] 'process raft request' (duration: 248.594229ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T04:58:03.60992Z","caller":"traceutil/trace.go:171","msg":"trace[674730752] transaction","detail":"{read_only:false; response_revision:6314; number_of_response:1; }","duration":"249.488739ms","start":"2026-10-02T04:58:03.360384Z","end":"2026-10-02T04:58:03.609873Z","steps":["trace[674730752] 'process raft request' (duration: 94.159984ms)","trace[674730752] 'compare' (duration: 154.042411ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T05:00:39.892893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5329} {"level":"info","ts":"2026-10-02T05:00:39.980355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5329,"took":"85.51164ms","hash":3554268615,"current-db-size-bytes":51519488,"current-db-size":"52 MB","current-db-size-in-use-bytes":18636800,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T05:00:39.980453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3554268615,"revision":5329,"compact-revision":2837}