{"level":"warn","ts":"2026-09-18T05:12:38.27367Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:12:38.273798Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.32:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.32:2380","--initial-cluster=instance=https://162.253.55.32:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.32:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.32:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-18T05:12:38.273901Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:12:38.273916Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-09-18T05:12:38.273947Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T05:12:38.274653Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"]} {"level":"info","ts":"2026-09-18T05:12:38.274746Z","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.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.32:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-18T05:12:38.28399Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.013135ms"} {"level":"info","ts":"2026-09-18T05:12:38.29151Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c665390cd9eacc14","cluster-id":"d19b610fbe64baae"} {"level":"info","ts":"2026-09-18T05:12:38.291605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:12:38.291639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 0"} {"level":"info","ts":"2026-09-18T05:12:38.291659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c665390cd9eacc14 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:12:38.291669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 1"} {"level":"info","ts":"2026-09-18T05:12:38.29173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"warn","ts":"2026-09-18T05:12:38.301999Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:12:38.303734Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:12:38.305614Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-18T05:12:38.308112Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c665390cd9eacc14","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:12:38.308256Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c665390cd9eacc14","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:12:38.30864Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T05:12:38.308755Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T05:12:38.308777Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-18T05:12:38.31092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"info","ts":"2026-09-18T05:12:38.311402Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","added-peer-id":"c665390cd9eacc14","added-peer-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-09-18T05:12:38.313332Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-18T05:12:38.313485Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-09-18T05:12:38.313582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-09-18T05:12:38.313792Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c665390cd9eacc14","initial-advertise-peer-urls":["https://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:12:38.313862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:12:38.39256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:12:38.392673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:12:38.392723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgPreVoteResp from c665390cd9eacc14 at term 1"} {"level":"info","ts":"2026-09-18T05:12:38.392741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:12:38.393064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgVoteResp from c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-09-18T05:12:38.393088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became leader at term 2"} {"level":"info","ts":"2026-09-18T05:12:38.39316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c665390cd9eacc14 elected leader c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-09-18T05:12:38.395639Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c665390cd9eacc14","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.32:2379]}","request-path":"/0/members/c665390cd9eacc14/attributes","cluster-id":"d19b610fbe64baae","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:12:38.39566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:12:38.396099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:12:38.396364Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:12:38.396538Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:12:38.39666Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:12:38.397588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:12:38.397695Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:12:38.397727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:12:38.399143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.32:2379"} {"level":"info","ts":"2026-09-18T05:12:38.39966Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:12:41.313385Z","caller":"traceutil/trace.go:171","msg":"trace[1754930068] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"114.329614ms","start":"2026-09-18T05:12:41.199026Z","end":"2026-09-18T05:12:41.313356Z","steps":["trace[1754930068] 'process raft request' (duration: 106.20308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:42.921021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.539225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:12:42.921565Z","caller":"traceutil/trace.go:171","msg":"trace[346062369] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"363.591266ms","start":"2026-09-18T05:12:42.557956Z","end":"2026-09-18T05:12:42.921547Z","steps":["trace[346062369] 'compare' (duration: 354.332491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:42.921759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:42.557936Z","time spent":"363.758139ms","remote":"127.0.0.1:38130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:12:43.13378Z","caller":"traceutil/trace.go:171","msg":"trace[1180857826] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:261; }","duration":"175.67738ms","start":"2026-09-18T05:12:42.958073Z","end":"2026-09-18T05:12:43.13375Z","steps":["trace[1180857826] 'read index received' (duration: 143.059521ms)","trace[1180857826] 'applied index is now lower than readState.Index' (duration: 32.616739ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:43.133898Z","caller":"traceutil/trace.go:171","msg":"trace[1824199032] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"177.122919ms","start":"2026-09-18T05:12:42.956742Z","end":"2026-09-18T05:12:43.133865Z","steps":["trace[1824199032] 'process raft request' (duration: 144.364717ms)","trace[1824199032] 'compare' (duration: 32.501237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:12:43.134201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.106828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-09-18T05:12:43.134281Z","caller":"traceutil/trace.go:171","msg":"trace[1848117506] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:256; }","duration":"176.20516ms","start":"2026-09-18T05:12:42.958035Z","end":"2026-09-18T05:12:43.13424Z","steps":["trace[1848117506] 'agreement among raft nodes before linearized reading' (duration: 175.939235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:43.469209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.14146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:12:43.469307Z","caller":"traceutil/trace.go:171","msg":"trace[1199432858] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:262; }","duration":"329.855669ms","start":"2026-09-18T05:12:43.139434Z","end":"2026-09-18T05:12:43.469289Z","steps":["trace[1199432858] 'read index received' (duration: 218.565516ms)","trace[1199432858] 'applied index is now lower than readState.Index' (duration: 111.288873ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:12:43.469376Z","caller":"traceutil/trace.go:171","msg":"trace[257427153] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"477.231121ms","start":"2026-09-18T05:12:42.992114Z","end":"2026-09-18T05:12:43.469345Z","steps":["trace[257427153] 'process raft request' (duration: 365.884717ms)","trace[257427153] 'compare' (duration: 110.980457ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:12:43.469415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.987183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:12:43.469674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:42.992098Z","time spent":"477.491378ms","remote":"127.0.0.1:38100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:12:43.469731Z","caller":"traceutil/trace.go:171","msg":"trace[1115696906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:257; }","duration":"330.332029ms","start":"2026-09-18T05:12:43.139379Z","end":"2026-09-18T05:12:43.469711Z","steps":["trace[1115696906] 'agreement among raft nodes before linearized reading' (duration: 329.965171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:43.470305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:43.139359Z","time spent":"330.908601ms","remote":"127.0.0.1:38130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"info","ts":"2026-09-18T05:12:43.479986Z","caller":"traceutil/trace.go:171","msg":"trace[1138436780] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"336.91667ms","start":"2026-09-18T05:12:43.143043Z","end":"2026-09-18T05:12:43.47996Z","steps":["trace[1138436780] 'process raft request' (duration: 336.730037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:12:43.480543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:12:43.143027Z","time spent":"337.031882ms","remote":"127.0.0.1:38090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:13:26.817594Z","caller":"traceutil/trace.go:171","msg":"trace[166102929] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"120.957208ms","start":"2026-09-18T05:13:26.696398Z","end":"2026-09-18T05:13:26.817355Z","steps":["trace[166102929] 'process raft request' (duration: 57.866742ms)","trace[166102929] 'compare' (duration: 62.641909ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:26.817588Z","caller":"traceutil/trace.go:171","msg":"trace[824130151] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"113.795055ms","start":"2026-09-18T05:13:26.703759Z","end":"2026-09-18T05:13:26.817554Z","steps":["trace[824130151] 'process raft request' (duration: 113.469509ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:26.818579Z","caller":"traceutil/trace.go:171","msg":"trace[1949360649] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"100.94391ms","start":"2026-09-18T05:13:26.717596Z","end":"2026-09-18T05:13:26.81854Z","steps":["trace[1949360649] 'process raft request' (duration: 100.738266ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:32.778733Z","caller":"traceutil/trace.go:171","msg":"trace[1902046922] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"302.040016ms","start":"2026-09-18T05:13:32.476666Z","end":"2026-09-18T05:13:32.778706Z","steps":["trace[1902046922] 'process raft request' (duration: 301.202633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:32.77886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.247074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:13:32.778912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:32.476632Z","time spent":"302.180307ms","remote":"127.0.0.1:37998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:13:32.778992Z","caller":"traceutil/trace.go:171","msg":"trace[1260116307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"217.465108ms","start":"2026-09-18T05:13:32.561511Z","end":"2026-09-18T05:13:32.778976Z","steps":["trace[1260116307] 'agreement among raft nodes before linearized reading' (duration: 217.265715ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:32.778685Z","caller":"traceutil/trace.go:171","msg":"trace[1709467701] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"217.058882ms","start":"2026-09-18T05:13:32.561586Z","end":"2026-09-18T05:13:32.778645Z","steps":["trace[1709467701] 'read index received' (duration: 215.197743ms)","trace[1709467701] 'applied index is now lower than readState.Index' (duration: 1.859519ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:33.183063Z","caller":"traceutil/trace.go:171","msg":"trace[123900481] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"337.889493ms","start":"2026-09-18T05:13:32.845141Z","end":"2026-09-18T05:13:33.183031Z","steps":["trace[123900481] 'read index received' (duration: 337.68986ms)","trace[123900481] 'applied index is now lower than readState.Index' (duration: 197.943µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:33.183151Z","caller":"traceutil/trace.go:171","msg":"trace[1663930238] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"365.640125ms","start":"2026-09-18T05:13:32.817464Z","end":"2026-09-18T05:13:33.183105Z","steps":["trace[1663930238] 'process raft request' (duration: 365.31482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:33.183341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:32.817417Z","time spent":"365.831768ms","remote":"127.0.0.1:38100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:13:33.1834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.232399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:13:33.183485Z","caller":"traceutil/trace.go:171","msg":"trace[1549452288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"338.37251ms","start":"2026-09-18T05:13:32.845088Z","end":"2026-09-18T05:13:33.18346Z","steps":["trace[1549452288] 'agreement among raft nodes before linearized reading' (duration: 338.079146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:33.183532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:32.845062Z","time spent":"338.458172ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T05:13:33.430696Z","caller":"traceutil/trace.go:171","msg":"trace[1606348461] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"566.897356ms","start":"2026-09-18T05:13:32.863755Z","end":"2026-09-18T05:13:33.430652Z","steps":["trace[1606348461] 'process raft request' (duration: 502.273953ms)","trace[1606348461] 'compare' (duration: 63.950682ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:33.430754Z","caller":"traceutil/trace.go:171","msg":"trace[2071250564] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"247.574472ms","start":"2026-09-18T05:13:33.183152Z","end":"2026-09-18T05:13:33.430726Z","steps":["trace[2071250564] 'read index received' (duration: 182.893238ms)","trace[2071250564] 'applied index is now lower than readState.Index' (duration: 64.678274ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:13:33.43076Z","caller":"traceutil/trace.go:171","msg":"trace[229249879] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"241.396317ms","start":"2026-09-18T05:13:33.189327Z","end":"2026-09-18T05:13:33.430723Z","steps":["trace[229249879] 'process raft request' (duration: 241.294885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:33.431154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.584979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T05:13:33.431219Z","caller":"traceutil/trace.go:171","msg":"trace[525736911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"357.722921ms","start":"2026-09-18T05:13:33.073481Z","end":"2026-09-18T05:13:33.431204Z","steps":["trace[525736911] 'agreement among raft nodes before linearized reading' (duration: 357.414106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:33.431276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:33.073451Z","time spent":"357.816173ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T05:13:33.431298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:13:32.863714Z","time spent":"567.094039ms","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:13:35.009007Z","caller":"traceutil/trace.go:171","msg":"trace[2050342957] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"110.716692ms","start":"2026-09-18T05:13:34.898251Z","end":"2026-09-18T05:13:35.008967Z","steps":["trace[2050342957] 'process raft request' (duration: 109.112147ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:46.976294Z","caller":"traceutil/trace.go:171","msg":"trace[728833575] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"102.687474ms","start":"2026-09-18T05:13:46.873575Z","end":"2026-09-18T05:13:46.976262Z","steps":["trace[728833575] 'process raft request' (duration: 102.479061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:49.763339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.86798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T05:13:49.76349Z","caller":"traceutil/trace.go:171","msg":"trace[1681593496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:824; }","duration":"234.055252ms","start":"2026-09-18T05:13:49.529411Z","end":"2026-09-18T05:13:49.763444Z","steps":["trace[1681593496] 'range keys from in-memory index tree' (duration: 233.625387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:49.763736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.775667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:13:49.763813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.244449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:13:49.76385Z","caller":"traceutil/trace.go:171","msg":"trace[561994151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"201.93022ms","start":"2026-09-18T05:13:49.561895Z","end":"2026-09-18T05:13:49.763825Z","steps":["trace[561994151] 'range keys from in-memory index tree' (duration: 201.703727ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:13:49.763887Z","caller":"traceutil/trace.go:171","msg":"trace[302545251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"239.381851ms","start":"2026-09-18T05:13:49.524491Z","end":"2026-09-18T05:13:49.763873Z","steps":["trace[302545251] 'range keys from in-memory index tree' (duration: 239.048086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:13:49.763897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.125143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:13:49.763947Z","caller":"traceutil/trace.go:171","msg":"trace[2051505736] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:824; }","duration":"270.463938ms","start":"2026-09-18T05:13:49.493467Z","end":"2026-09-18T05:13:49.763931Z","steps":["trace[2051505736] 'count revisions from in-memory index tree' (duration: 270.027121ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:14:48.47359Z","caller":"traceutil/trace.go:171","msg":"trace[57626458] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"159.229829ms","start":"2026-09-18T05:14:48.314327Z","end":"2026-09-18T05:14:48.473556Z","steps":["trace[57626458] 'process raft request' (duration: 158.995556ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:00.719429Z","caller":"traceutil/trace.go:171","msg":"trace[178310572] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"105.868615ms","start":"2026-09-18T05:15:00.61353Z","end":"2026-09-18T05:15:00.719399Z","steps":["trace[178310572] 'process raft request' (duration: 105.680952ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:15.501079Z","caller":"traceutil/trace.go:171","msg":"trace[1841508114] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"183.524264ms","start":"2026-09-18T05:15:15.317512Z","end":"2026-09-18T05:15:15.501036Z","steps":["trace[1841508114] 'process raft request' (duration: 183.105988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:15.664461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.232592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-18T05:15:15.664585Z","caller":"traceutil/trace.go:171","msg":"trace[1787371875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1928; }","duration":"111.435184ms","start":"2026-09-18T05:15:15.553126Z","end":"2026-09-18T05:15:15.664562Z","steps":["trace[1787371875] 'range keys from in-memory index tree' (duration: 110.946538ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:15.936051Z","caller":"traceutil/trace.go:171","msg":"trace[402267007] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"231.72652ms","start":"2026-09-18T05:15:15.704273Z","end":"2026-09-18T05:15:15.935999Z","steps":["trace[402267007] 'process raft request' (duration: 213.584262ms)","trace[402267007] 'compare' (duration: 17.360909ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:54.620302Z","caller":"traceutil/trace.go:171","msg":"trace[1805453334] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"144.549373ms","start":"2026-09-18T05:15:54.475718Z","end":"2026-09-18T05:15:54.620267Z","steps":["trace[1805453334] 'process raft request' (duration: 144.368771ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:54.829312Z","caller":"traceutil/trace.go:171","msg":"trace[499139903] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2273; }","duration":"270.168405ms","start":"2026-09-18T05:15:54.559106Z","end":"2026-09-18T05:15:54.829275Z","steps":["trace[499139903] 'read index received' (duration: 60.871276ms)","trace[499139903] 'applied index is now lower than readState.Index' (duration: 209.295429ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:15:54.829386Z","caller":"traceutil/trace.go:171","msg":"trace[1758427236] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"272.488683ms","start":"2026-09-18T05:15:54.556825Z","end":"2026-09-18T05:15:54.829313Z","steps":["trace[1758427236] 'process raft request' (duration: 262.103715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:54.829649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.524038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:15:54.830029Z","caller":"traceutil/trace.go:171","msg":"trace[821089734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2188; }","duration":"270.954374ms","start":"2026-09-18T05:15:54.55905Z","end":"2026-09-18T05:15:54.830005Z","steps":["trace[821089734] 'agreement among raft nodes before linearized reading' (duration: 270.336587ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:15:54.838301Z","caller":"traceutil/trace.go:171","msg":"trace[1427144489] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"174.766035ms","start":"2026-09-18T05:15:54.663508Z","end":"2026-09-18T05:15:54.838274Z","steps":["trace[1427144489] 'process raft request' (duration: 174.493791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:54.838609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.095303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:15:54.838676Z","caller":"traceutil/trace.go:171","msg":"trace[687619843] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2190; }","duration":"261.220034ms","start":"2026-09-18T05:15:54.577437Z","end":"2026-09-18T05:15:54.838657Z","steps":["trace[687619843] 'agreement among raft nodes before linearized reading' (duration: 261.056312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:54.838707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.987868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:15:54.838767Z","caller":"traceutil/trace.go:171","msg":"trace[579114240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2190; }","duration":"264.129701ms","start":"2026-09-18T05:15:54.574624Z","end":"2026-09-18T05:15:54.838754Z","steps":["trace[579114240] 'agreement among raft nodes before linearized reading' (duration: 263.956198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:15:54.839395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.341627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T05:15:54.839973Z","caller":"traceutil/trace.go:171","msg":"trace[2089242227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2190; }","duration":"257.417998ms","start":"2026-09-18T05:15:54.582023Z","end":"2026-09-18T05:15:54.839441Z","steps":["trace[2089242227] 'agreement among raft nodes before linearized reading' (duration: 257.242186ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:33.352214Z","caller":"traceutil/trace.go:171","msg":"trace[2038145293] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"109.544177ms","start":"2026-09-18T05:17:33.24263Z","end":"2026-09-18T05:17:33.352175Z","steps":["trace[2038145293] 'process raft request' (duration: 109.35909ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:33.370873Z","caller":"traceutil/trace.go:171","msg":"trace[912359044] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"103.994166ms","start":"2026-09-18T05:17:33.266848Z","end":"2026-09-18T05:17:33.370843Z","steps":["trace[912359044] 'process raft request' (duration: 103.943424ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:33.370994Z","caller":"traceutil/trace.go:171","msg":"trace[2045569884] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"105.479633ms","start":"2026-09-18T05:17:33.26549Z","end":"2026-09-18T05:17:33.37097Z","steps":["trace[2045569884] 'process raft request' (duration: 105.161751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:33.58583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.584413ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:17:33.586126Z","caller":"traceutil/trace.go:171","msg":"trace[1993206755] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2826; }","duration":"135.907335ms","start":"2026-09-18T05:17:33.450197Z","end":"2026-09-18T05:17:33.586104Z","steps":["trace[1993206755] 'range keys from in-memory index tree' (duration: 135.562862ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:33.58626Z","caller":"traceutil/trace.go:171","msg":"trace[897496270] linearizableReadLoop","detail":"{readStateIndex:2936; appliedIndex:2935; }","duration":"186.157768ms","start":"2026-09-18T05:17:33.40008Z","end":"2026-09-18T05:17:33.586238Z","steps":["trace[897496270] 'read index received' (duration: 97.871355ms)","trace[897496270] 'applied index is now lower than readState.Index' (duration: 88.284973ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:33.586361Z","caller":"traceutil/trace.go:171","msg":"trace[177214653] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"188.134642ms","start":"2026-09-18T05:17:33.398161Z","end":"2026-09-18T05:17:33.586296Z","steps":["trace[177214653] 'process raft request' (duration: 99.85254ms)","trace[177214653] 'compare' (duration: 88.084235ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:17:33.586532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.513826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-18T05:17:33.586594Z","caller":"traceutil/trace.go:171","msg":"trace[1255707946] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2827; }","duration":"165.708183ms","start":"2026-09-18T05:17:33.420867Z","end":"2026-09-18T05:17:33.586575Z","steps":["trace[1255707946] 'agreement among raft nodes before linearized reading' (duration: 165.515276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:33.587528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.403475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:17:33.58772Z","caller":"traceutil/trace.go:171","msg":"trace[639020820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2827; }","duration":"187.633704ms","start":"2026-09-18T05:17:33.400056Z","end":"2026-09-18T05:17:33.58769Z","steps":["trace[639020820] 'agreement among raft nodes before linearized reading' (duration: 186.249591ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:22.199195Z","caller":"traceutil/trace.go:171","msg":"trace[1410437236] transaction","detail":"{read_only:false; response_revision:3780; number_of_response:1; }","duration":"115.929119ms","start":"2026-09-18T05:20:22.083237Z","end":"2026-09-18T05:20:22.199166Z","steps":["trace[1410437236] 'process raft request' (duration: 102.833452ms)","trace[1410437236] 'compare' (duration: 12.77557ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:20:22.199894Z","caller":"traceutil/trace.go:171","msg":"trace[1683662792] transaction","detail":"{read_only:false; response_revision:3781; number_of_response:1; }","duration":"116.168944ms","start":"2026-09-18T05:20:22.083672Z","end":"2026-09-18T05:20:22.199841Z","steps":["trace[1683662792] 'process raft request' (duration: 115.439908ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:20:22.200213Z","caller":"traceutil/trace.go:171","msg":"trace[665692255] transaction","detail":"{read_only:false; response_revision:3782; number_of_response:1; }","duration":"114.285913ms","start":"2026-09-18T05:20:22.085843Z","end":"2026-09-18T05:20:22.200129Z","steps":["trace[665692255] 'process raft request' (duration: 113.69505ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:21:59.457536Z","caller":"traceutil/trace.go:171","msg":"trace[1185552756] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"179.881576ms","start":"2026-09-18T05:21:59.27763Z","end":"2026-09-18T05:21:59.457511Z","steps":["trace[1185552756] 'process raft request' (duration: 179.603931ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.963689Z","caller":"traceutil/trace.go:171","msg":"trace[1069522527] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"155.460453ms","start":"2026-09-18T05:22:06.808176Z","end":"2026-09-18T05:22:06.963636Z","steps":["trace[1069522527] 'process raft request' (duration: 116.416804ms)","trace[1069522527] 'compare' (duration: 38.693402ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:06.963903Z","caller":"traceutil/trace.go:171","msg":"trace[1518517851] transaction","detail":"{read_only:false; response_revision:4473; number_of_response:1; }","duration":"155.433533ms","start":"2026-09-18T05:22:06.808452Z","end":"2026-09-18T05:22:06.963885Z","steps":["trace[1518517851] 'process raft request' (duration: 155.176348ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964012Z","caller":"traceutil/trace.go:171","msg":"trace[1868798143] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"155.606986ms","start":"2026-09-18T05:22:06.808396Z","end":"2026-09-18T05:22:06.964003Z","steps":["trace[1868798143] 'process raft request' (duration: 155.154388ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964086Z","caller":"traceutil/trace.go:171","msg":"trace[1767187524] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"155.108137ms","start":"2026-09-18T05:22:06.80897Z","end":"2026-09-18T05:22:06.964078Z","steps":["trace[1767187524] 'process raft request' (duration: 154.812952ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964147Z","caller":"traceutil/trace.go:171","msg":"trace[175463128] transaction","detail":"{read_only:false; response_revision:4475; number_of_response:1; }","duration":"155.236679ms","start":"2026-09-18T05:22:06.808903Z","end":"2026-09-18T05:22:06.96414Z","steps":["trace[175463128] 'process raft request' (duration: 154.835402ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964213Z","caller":"traceutil/trace.go:171","msg":"trace[1504538715] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"154.25661ms","start":"2026-09-18T05:22:06.809947Z","end":"2026-09-18T05:22:06.964204Z","steps":["trace[1504538715] 'process raft request' (duration: 154.109698ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964277Z","caller":"traceutil/trace.go:171","msg":"trace[1933299589] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"151.649722ms","start":"2026-09-18T05:22:06.81262Z","end":"2026-09-18T05:22:06.96427Z","steps":["trace[1933299589] 'process raft request' (duration: 151.54367ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964289Z","caller":"traceutil/trace.go:171","msg":"trace[834007499] transaction","detail":"{read_only:false; response_revision:4474; number_of_response:1; }","duration":"155.437474ms","start":"2026-09-18T05:22:06.808714Z","end":"2026-09-18T05:22:06.964152Z","steps":["trace[834007499] 'process raft request' (duration: 154.984665ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964231Z","caller":"traceutil/trace.go:171","msg":"trace[329356475] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"151.656402ms","start":"2026-09-18T05:22:06.812558Z","end":"2026-09-18T05:22:06.964214Z","steps":["trace[329356475] 'process raft request' (duration: 151.55871ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964323Z","caller":"traceutil/trace.go:171","msg":"trace[1161115249] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"154.264861ms","start":"2026-09-18T05:22:06.809917Z","end":"2026-09-18T05:22:06.964182Z","steps":["trace[1161115249] 'process raft request' (duration: 154.087828ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964366Z","caller":"traceutil/trace.go:171","msg":"trace[1410829757] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"154.76357ms","start":"2026-09-18T05:22:06.809584Z","end":"2026-09-18T05:22:06.964348Z","steps":["trace[1410829757] 'process raft request' (duration: 154.265181ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964518Z","caller":"traceutil/trace.go:171","msg":"trace[1568565420] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"151.149323ms","start":"2026-09-18T05:22:06.81335Z","end":"2026-09-18T05:22:06.9645Z","steps":["trace[1568565420] 'process raft request' (duration: 150.99949ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964549Z","caller":"traceutil/trace.go:171","msg":"trace[111561132] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"151.471648ms","start":"2026-09-18T05:22:06.813064Z","end":"2026-09-18T05:22:06.964536Z","steps":["trace[111561132] 'process raft request' (duration: 151.180763ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964155Z","caller":"traceutil/trace.go:171","msg":"trace[1403651014] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"154.313972ms","start":"2026-09-18T05:22:06.809818Z","end":"2026-09-18T05:22:06.964132Z","steps":["trace[1403651014] 'process raft request' (duration: 154.086978ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964567Z","caller":"traceutil/trace.go:171","msg":"trace[175531979] transaction","detail":"{read_only:false; response_revision:4483; number_of_response:1; }","duration":"151.397327ms","start":"2026-09-18T05:22:06.812948Z","end":"2026-09-18T05:22:06.964345Z","steps":["trace[175531979] 'process raft request' (duration: 151.259915ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964741Z","caller":"traceutil/trace.go:171","msg":"trace[5289579] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"150.886237ms","start":"2026-09-18T05:22:06.813834Z","end":"2026-09-18T05:22:06.96472Z","steps":["trace[5289579] 'process raft request' (duration: 150.688473ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964639Z","caller":"traceutil/trace.go:171","msg":"trace[458786913] transaction","detail":"{read_only:false; response_revision:4485; number_of_response:1; }","duration":"151.357436ms","start":"2026-09-18T05:22:06.813124Z","end":"2026-09-18T05:22:06.964481Z","steps":["trace[458786913] 'process raft request' (duration: 151.174443ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964851Z","caller":"traceutil/trace.go:171","msg":"trace[321517352] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"149.875599ms","start":"2026-09-18T05:22:06.814964Z","end":"2026-09-18T05:22:06.964839Z","steps":["trace[321517352] 'process raft request' (duration: 149.653314ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964874Z","caller":"traceutil/trace.go:171","msg":"trace[552193999] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"148.512423ms","start":"2026-09-18T05:22:06.816343Z","end":"2026-09-18T05:22:06.964855Z","steps":["trace[552193999] 'process raft request' (duration: 148.454682ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964937Z","caller":"traceutil/trace.go:171","msg":"trace[345118063] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"149.856109ms","start":"2026-09-18T05:22:06.815042Z","end":"2026-09-18T05:22:06.964898Z","steps":["trace[345118063] 'process raft request' (duration: 149.660464ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964999Z","caller":"traceutil/trace.go:171","msg":"trace[1328788420] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"150.104504ms","start":"2026-09-18T05:22:06.814872Z","end":"2026-09-18T05:22:06.964977Z","steps":["trace[1328788420] 'process raft request' (duration: 149.699346ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964707Z","caller":"traceutil/trace.go:171","msg":"trace[1306026110] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"151.125172ms","start":"2026-09-18T05:22:06.813533Z","end":"2026-09-18T05:22:06.964658Z","steps":["trace[1306026110] 'process raft request' (duration: 150.944898ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.964746Z","caller":"traceutil/trace.go:171","msg":"trace[2083045054] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"151.056201ms","start":"2026-09-18T05:22:06.813511Z","end":"2026-09-18T05:22:06.964567Z","steps":["trace[2083045054] 'process raft request' (duration: 150.925309ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.965059Z","caller":"traceutil/trace.go:171","msg":"trace[1100596756] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"149.519603ms","start":"2026-09-18T05:22:06.815523Z","end":"2026-09-18T05:22:06.965042Z","steps":["trace[1100596756] 'process raft request' (duration: 149.229257ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:06.965019Z","caller":"traceutil/trace.go:171","msg":"trace[593710852] transaction","detail":"{read_only:false; response_revision:4487; number_of_response:1; }","duration":"151.551581ms","start":"2026-09-18T05:22:06.813456Z","end":"2026-09-18T05:22:06.965008Z","steps":["trace[593710852] 'process raft request' (duration: 150.932499ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:17.574321Z","caller":"traceutil/trace.go:171","msg":"trace[1090679631] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"188.687924ms","start":"2026-09-18T05:22:17.385437Z","end":"2026-09-18T05:22:17.574125Z","steps":["trace[1090679631] 'process raft request' (duration: 188.541012ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:17.574385Z","caller":"traceutil/trace.go:171","msg":"trace[924306028] linearizableReadLoop","detail":"{readStateIndex:5187; appliedIndex:5187; }","duration":"167.428261ms","start":"2026-09-18T05:22:17.406935Z","end":"2026-09-18T05:22:17.574363Z","steps":["trace[924306028] 'read index received' (duration: 167.422731ms)","trace[924306028] 'applied index is now lower than readState.Index' (duration: 4.61µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:22:17.574587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.633454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b9096c2-62c5-4b52-9080-974762fe0dc0\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-09-18T05:22:17.57465Z","caller":"traceutil/trace.go:171","msg":"trace[1998256345] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b9096c2-62c5-4b52-9080-974762fe0dc0; range_end:; response_count:1; response_revision:4993; }","duration":"167.738248ms","start":"2026-09-18T05:22:17.4069Z","end":"2026-09-18T05:22:17.574638Z","steps":["trace[1998256345] 'agreement among raft nodes before linearized reading' (duration: 167.560794ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:24.79092Z","caller":"traceutil/trace.go:171","msg":"trace[2032507341] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5277; }","duration":"194.403696ms","start":"2026-09-18T05:22:24.596488Z","end":"2026-09-18T05:22:24.790891Z","steps":["trace[2032507341] 'read index received' (duration: 175.255485ms)","trace[2032507341] 'applied index is now lower than readState.Index' (duration: 19.146941ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:24.791107Z","caller":"traceutil/trace.go:171","msg":"trace[1802124238] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"208.31589ms","start":"2026-09-18T05:22:24.582772Z","end":"2026-09-18T05:22:24.791088Z","steps":["trace[1802124238] 'process raft request' (duration: 189.041527ms)","trace[1802124238] 'compare' (duration: 18.970068ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:22:24.791506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.992477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-18T05:22:24.791597Z","caller":"traceutil/trace.go:171","msg":"trace[849292446] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5079; }","duration":"195.119239ms","start":"2026-09-18T05:22:24.596458Z","end":"2026-09-18T05:22:24.791577Z","steps":["trace[849292446] 'agreement among raft nodes before linearized reading' (duration: 194.877844ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:24.791744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.074182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T05:22:24.791829Z","caller":"traceutil/trace.go:171","msg":"trace[1634864186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5079; }","duration":"192.204105ms","start":"2026-09-18T05:22:24.599608Z","end":"2026-09-18T05:22:24.791812Z","steps":["trace[1634864186] 'agreement among raft nodes before linearized reading' (duration: 191.95538ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:24.972497Z","caller":"traceutil/trace.go:171","msg":"trace[1048729664] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"104.388785ms","start":"2026-09-18T05:22:24.868048Z","end":"2026-09-18T05:22:24.972437Z","steps":["trace[1048729664] 'process raft request' (duration: 22.700207ms)","trace[1048729664] 'compare' (duration: 81.298121ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:22:28.729136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.74442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a131259-e725-4461-ad0b-19059cf20313\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-18T05:22:28.729233Z","caller":"traceutil/trace.go:171","msg":"trace[1240927525] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a131259-e725-4461-ad0b-19059cf20313; range_end:; response_count:1; response_revision:5179; }","duration":"118.885153ms","start":"2026-09-18T05:22:28.610322Z","end":"2026-09-18T05:22:28.729208Z","steps":["trace[1240927525] 'range keys from in-memory index tree' (duration: 118.355063ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:30.963281Z","caller":"traceutil/trace.go:171","msg":"trace[428878867] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"286.19825ms","start":"2026-09-18T05:22:30.677052Z","end":"2026-09-18T05:22:30.96325Z","steps":["trace[428878867] 'process raft request' (duration: 285.976916ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:30.980309Z","caller":"traceutil/trace.go:171","msg":"trace[176500448] linearizableReadLoop","detail":"{readStateIndex:5402; appliedIndex:5400; }","duration":"134.216848ms","start":"2026-09-18T05:22:30.846069Z","end":"2026-09-18T05:22:30.980286Z","steps":["trace[176500448] 'read index received' (duration: 117.171317ms)","trace[176500448] 'applied index is now lower than readState.Index' (duration: 17.044271ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:22:30.980384Z","caller":"traceutil/trace.go:171","msg":"trace[386463105] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"143.927155ms","start":"2026-09-18T05:22:30.836436Z","end":"2026-09-18T05:22:30.980363Z","steps":["trace[386463105] 'process raft request' (duration: 143.716451ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:30.980403Z","caller":"traceutil/trace.go:171","msg":"trace[1924400318] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"102.462698ms","start":"2026-09-18T05:22:30.877916Z","end":"2026-09-18T05:22:30.980379Z","steps":["trace[1924400318] 'process raft request' (duration: 102.294795ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:30.980465Z","caller":"traceutil/trace.go:171","msg":"trace[109917635] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"101.526412ms","start":"2026-09-18T05:22:30.878926Z","end":"2026-09-18T05:22:30.980452Z","steps":["trace[109917635] 'process raft request' (duration: 101.321049ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:30.980763Z","caller":"traceutil/trace.go:171","msg":"trace[2092428442] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"207.414393ms","start":"2026-09-18T05:22:30.773333Z","end":"2026-09-18T05:22:30.980748Z","steps":["trace[2092428442] 'process raft request' (duration: 206.19876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:30.980816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.390211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T05:22:30.980876Z","caller":"traceutil/trace.go:171","msg":"trace[162078244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5204; }","duration":"106.495923ms","start":"2026-09-18T05:22:30.874366Z","end":"2026-09-18T05:22:30.980862Z","steps":["trace[162078244] 'agreement among raft nodes before linearized reading' (duration: 106.309449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:22:30.980813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.730717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a131259-e725-4461-ad0b-19059cf20313\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-09-18T05:22:30.981037Z","caller":"traceutil/trace.go:171","msg":"trace[639784155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a131259-e725-4461-ad0b-19059cf20313; range_end:; response_count:1; response_revision:5204; }","duration":"134.991551ms","start":"2026-09-18T05:22:30.846029Z","end":"2026-09-18T05:22:30.98102Z","steps":["trace[639784155] 'agreement among raft nodes before linearized reading' (duration: 134.572574ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:34.904424Z","caller":"traceutil/trace.go:171","msg":"trace[1376247195] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"167.805709ms","start":"2026-09-18T05:22:34.736592Z","end":"2026-09-18T05:22:34.904398Z","steps":["trace[1376247195] 'process raft request' (duration: 167.564274ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:38.667397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2853} {"level":"info","ts":"2026-09-18T05:22:38.749727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2853,"took":"80.944485ms","hash":4179863720,"current-db-size-bytes":51281920,"current-db-size":"51 MB","current-db-size-in-use-bytes":27611136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T05:22:38.749853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4179863720,"revision":2853,"compact-revision":-1} {"level":"info","ts":"2026-09-18T05:27:38.680763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5320} {"level":"info","ts":"2026-09-18T05:27:38.761744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5320,"took":"79.526267ms","hash":618587997,"current-db-size-bytes":51281920,"current-db-size":"51 MB","current-db-size-in-use-bytes":18952192,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T05:27:38.76181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":618587997,"revision":5320,"compact-revision":2853}