{"level":"warn","ts":"2026-09-10T01:51:11.221198Z","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-10T01:51:11.22137Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.19: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.19:2380","--initial-cluster=instance=https://162.253.55.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.19: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-10T01:51:11.221461Z","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-10T01:51:11.221475Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-09-10T01:51:11.221498Z","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-10T01:51:11.222269Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"]} {"level":"info","ts":"2026-09-10T01:51:11.222391Z","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.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.19: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-10T01:51:11.229495Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.872697ms"} {"level":"info","ts":"2026-09-10T01:51:11.239995Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ded76e421897a43d","cluster-id":"8853d1107d87769b"} {"level":"info","ts":"2026-09-10T01:51:11.240116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T01:51:11.240147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 0"} {"level":"info","ts":"2026-09-10T01:51:11.240162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ded76e421897a43d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T01:51:11.240182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became follower at term 1"} {"level":"info","ts":"2026-09-10T01:51:11.24024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"warn","ts":"2026-09-10T01:51:11.254999Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T01:51:11.258585Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T01:51:11.261479Z","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-10T01:51:11.265378Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ded76e421897a43d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T01:51:11.265633Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ded76e421897a43d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T01:51:11.266003Z","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-10T01:51:11.266075Z","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-10T01:51:11.266103Z","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-10T01:51:11.277934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d switched to configuration voters=(16057424226572280893)"} {"level":"info","ts":"2026-09-10T01:51:11.278072Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","added-peer-id":"ded76e421897a43d","added-peer-peer-urls":["https://162.253.55.19:2380"]} {"level":"info","ts":"2026-09-10T01:51:11.278852Z","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-10T01:51:11.279057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-09-10T01:51:11.279092Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.19:2380"} {"level":"info","ts":"2026-09-10T01:51:11.279243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ded76e421897a43d","initial-advertise-peer-urls":["https://162.253.55.19:2380"],"listen-peer-urls":["https://162.253.55.19:2380"],"advertise-client-urls":["https://162.253.55.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T01:51:11.279298Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T01:51:12.141334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T01:51:12.141604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T01:51:12.141718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgPreVoteResp from ded76e421897a43d at term 1"} {"level":"info","ts":"2026-09-10T01:51:12.141831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became candidate at term 2"} {"level":"info","ts":"2026-09-10T01:51:12.141967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d received MsgVoteResp from ded76e421897a43d at term 2"} {"level":"info","ts":"2026-09-10T01:51:12.142048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ded76e421897a43d became leader at term 2"} {"level":"info","ts":"2026-09-10T01:51:12.142163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ded76e421897a43d elected leader ded76e421897a43d at term 2"} {"level":"info","ts":"2026-09-10T01:51:12.14324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ded76e421897a43d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.19:2379]}","request-path":"/0/members/ded76e421897a43d/attributes","cluster-id":"8853d1107d87769b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T01:51:12.143257Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:51:12.143428Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:51:12.144105Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:51:12.144296Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8853d1107d87769b","local-member-id":"ded76e421897a43d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:51:12.144338Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T01:51:12.144357Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T01:51:12.144386Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:51:12.144412Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:51:12.147248Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T01:51:12.16493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.19:2379"} {"level":"info","ts":"2026-09-10T01:51:17.505943Z","caller":"traceutil/trace.go:171","msg":"trace[1298508989] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:270; }","duration":"128.512863ms","start":"2026-09-10T01:51:17.37738Z","end":"2026-09-10T01:51:17.505892Z","steps":["trace[1298508989] 'read index received' (duration: 59.594984ms)","trace[1298508989] 'applied index is now lower than readState.Index' (duration: 68.916639ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:51:17.505967Z","caller":"traceutil/trace.go:171","msg":"trace[1750948742] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"134.965558ms","start":"2026-09-10T01:51:17.370977Z","end":"2026-09-10T01:51:17.505943Z","steps":["trace[1750948742] 'process raft request' (duration: 134.771112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:51:17.506189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.781362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:51:17.506247Z","caller":"traceutil/trace.go:171","msg":"trace[1373733391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:268; }","duration":"128.891976ms","start":"2026-09-10T01:51:17.377346Z","end":"2026-09-10T01:51:17.506238Z","steps":["trace[1373733391] 'agreement among raft nodes before linearized reading' (duration: 128.786542ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:51:17.506314Z","caller":"traceutil/trace.go:171","msg":"trace[1850854844] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"134.785521ms","start":"2026-09-10T01:51:17.371519Z","end":"2026-09-10T01:51:17.506305Z","steps":["trace[1850854844] 'process raft request' (duration: 134.300444ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:51:17.505993Z","caller":"traceutil/trace.go:171","msg":"trace[1128903739] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"138.295435ms","start":"2026-09-10T01:51:17.367688Z","end":"2026-09-10T01:51:17.505984Z","steps":["trace[1128903739] 'process raft request' (duration: 69.102216ms)","trace[1128903739] 'compare' (duration: 68.693031ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:51:31.474592Z","caller":"traceutil/trace.go:171","msg":"trace[400265566] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"193.454041ms","start":"2026-09-10T01:51:31.281096Z","end":"2026-09-10T01:51:31.47455Z","steps":["trace[400265566] 'process raft request' (duration: 193.203933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:51:31.786395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.874594ms","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-10T01:51:31.786481Z","caller":"traceutil/trace.go:171","msg":"trace[2111142420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"202.040549ms","start":"2026-09-10T01:51:31.584421Z","end":"2026-09-10T01:51:31.786462Z","steps":["trace[2111142420] 'range keys from in-memory index tree' (duration: 201.703959ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:51:52.949633Z","caller":"traceutil/trace.go:171","msg":"trace[959724594] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"120.130227ms","start":"2026-09-10T01:51:52.829452Z","end":"2026-09-10T01:51:52.949582Z","steps":["trace[959724594] 'process raft request' (duration: 99.337767ms)","trace[959724594] 'compare' (duration: 20.652847ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:51:58.459171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.915324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:51:58.459502Z","caller":"traceutil/trace.go:171","msg":"trace[866526937] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"285.057899ms","start":"2026-09-10T01:51:58.174384Z","end":"2026-09-10T01:51:58.459442Z","steps":["trace[866526937] 'process raft request' (duration: 104.300896ms)","trace[866526937] 'compare' (duration: 179.627727ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:00.36242Z","caller":"traceutil/trace.go:171","msg":"trace[1803394166] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"311.686503ms","start":"2026-09-10T01:52:00.050705Z","end":"2026-09-10T01:52:00.362392Z","steps":["trace[1803394166] 'read index received' (duration: 311.513349ms)","trace[1803394166] 'applied index is now lower than readState.Index' (duration: 171.974µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:00.362493Z","caller":"traceutil/trace.go:171","msg":"trace[1462038182] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"339.083349ms","start":"2026-09-10T01:52:00.023376Z","end":"2026-09-10T01:52:00.362459Z","steps":["trace[1462038182] 'process raft request' (duration: 338.880444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:00.362609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.880058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:52:00.36265Z","caller":"traceutil/trace.go:171","msg":"trace[1495612846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"311.96618ms","start":"2026-09-10T01:52:00.050673Z","end":"2026-09-10T01:52:00.362639Z","steps":["trace[1495612846] 'agreement among raft nodes before linearized reading' (duration: 311.885568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:00.36265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:00.02336Z","time spent":"339.210162ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:52:00.362696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:00.050658Z","time spent":"312.016171ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T01:52:00.362947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.163872ms","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-10T01:52:00.363005Z","caller":"traceutil/trace.go:171","msg":"trace[708036196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:702; }","duration":"224.260074ms","start":"2026-09-10T01:52:00.138727Z","end":"2026-09-10T01:52:00.362987Z","steps":["trace[708036196] 'agreement among raft nodes before linearized reading' (duration: 224.083371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:00.363356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.90906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-10T01:52:00.363419Z","caller":"traceutil/trace.go:171","msg":"trace[1441519821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:702; }","duration":"222.002003ms","start":"2026-09-10T01:52:00.141404Z","end":"2026-09-10T01:52:00.363406Z","steps":["trace[1441519821] 'agreement among raft nodes before linearized reading' (duration: 221.533922ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:00.53607Z","caller":"traceutil/trace.go:171","msg":"trace[736576458] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"168.636795ms","start":"2026-09-10T01:52:00.36739Z","end":"2026-09-10T01:52:00.536027Z","steps":["trace[736576458] 'process raft request' (duration: 168.565693ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:00.536127Z","caller":"traceutil/trace.go:171","msg":"trace[736360614] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"169.182497ms","start":"2026-09-10T01:52:00.366917Z","end":"2026-09-10T01:52:00.5361Z","steps":["trace[736360614] 'process raft request' (duration: 97.280593ms)","trace[736360614] 'compare' (duration: 71.632288ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:00.796171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.305073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:52:00.796289Z","caller":"traceutil/trace.go:171","msg":"trace[1454388651] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"216.395186ms","start":"2026-09-10T01:52:00.579872Z","end":"2026-09-10T01:52:00.796267Z","steps":["trace[1454388651] 'process raft request' (duration: 87.865249ms)","trace[1454388651] 'compare' (duration: 128.161358ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:01.708896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.595454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-10T01:52:01.709007Z","caller":"traceutil/trace.go:171","msg":"trace[1320801201] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"859.113916ms","start":"2026-09-10T01:52:00.849871Z","end":"2026-09-10T01:52:01.708985Z","steps":["trace[1320801201] 'read index received' (duration: 222.397379ms)","trace[1320801201] 'applied index is now lower than readState.Index' (duration: 636.714817ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:01.70929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.368701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-10T01:52:01.709344Z","caller":"traceutil/trace.go:171","msg":"trace[1160539264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"859.492115ms","start":"2026-09-10T01:52:00.849837Z","end":"2026-09-10T01:52:01.709329Z","steps":["trace[1160539264] 'agreement among raft nodes before linearized reading' (duration: 859.268489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:01.7094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:52:00.849821Z","time spent":"859.565616ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-10T01:52:18.638932Z","caller":"traceutil/trace.go:171","msg":"trace[1418452321] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"183.290423ms","start":"2026-09-10T01:52:18.45562Z","end":"2026-09-10T01:52:18.638911Z","steps":["trace[1418452321] 'read index received' (duration: 183.204481ms)","trace[1418452321] 'applied index is now lower than readState.Index' (duration: 85.192µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:52:18.638976Z","caller":"traceutil/trace.go:171","msg":"trace[898132045] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"253.425549ms","start":"2026-09-10T01:52:18.385529Z","end":"2026-09-10T01:52:18.638955Z","steps":["trace[898132045] 'process raft request' (duration: 253.268586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:18.639197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.486997ms","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-10T01:52:18.639467Z","caller":"traceutil/trace.go:171","msg":"trace[1999365475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"183.857994ms","start":"2026-09-10T01:52:18.455594Z","end":"2026-09-10T01:52:18.639452Z","steps":["trace[1999365475] 'agreement among raft nodes before linearized reading' (duration: 183.433745ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:18.745482Z","caller":"traceutil/trace.go:171","msg":"trace[180267315] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"119.000472ms","start":"2026-09-10T01:52:18.626453Z","end":"2026-09-10T01:52:18.745454Z","steps":["trace[180267315] 'process raft request' (duration: 110.393602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:52:18.745808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.5423ms","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-10T01:52:18.745915Z","caller":"traceutil/trace.go:171","msg":"trace[837575750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"134.700693ms","start":"2026-09-10T01:52:18.611198Z","end":"2026-09-10T01:52:18.745898Z","steps":["trace[837575750] 'agreement among raft nodes before linearized reading' (duration: 134.338776ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:18.745502Z","caller":"traceutil/trace.go:171","msg":"trace[1954659533] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"106.474765ms","start":"2026-09-10T01:52:18.639008Z","end":"2026-09-10T01:52:18.745483Z","steps":["trace[1954659533] 'read index received' (duration: 97.849864ms)","trace[1954659533] 'applied index is now lower than readState.Index' (duration: 8.622651ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:52:18.745931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.942409ms","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-10T01:52:18.746086Z","caller":"traceutil/trace.go:171","msg":"trace[952540978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:824; }","duration":"126.114014ms","start":"2026-09-10T01:52:18.619956Z","end":"2026-09-10T01:52:18.74607Z","steps":["trace[952540978] 'agreement among raft nodes before linearized reading' (duration: 125.593393ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:18.745477Z","caller":"traceutil/trace.go:171","msg":"trace[1062751927] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"101.768901ms","start":"2026-09-10T01:52:18.643683Z","end":"2026-09-10T01:52:18.745452Z","steps":["trace[1062751927] 'process raft request' (duration: 101.7101ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:52:49.375519Z","caller":"traceutil/trace.go:171","msg":"trace[1004029504] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"161.447264ms","start":"2026-09-10T01:52:49.214026Z","end":"2026-09-10T01:52:49.375473Z","steps":["trace[1004029504] 'process raft request' (duration: 161.278682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:14.166678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.311454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:53:14.166822Z","caller":"traceutil/trace.go:171","msg":"trace[263642727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1496; }","duration":"115.526656ms","start":"2026-09-10T01:53:14.051263Z","end":"2026-09-10T01:53:14.166789Z","steps":["trace[263642727] 'range keys from in-memory index tree' (duration: 115.198482ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:29.679785Z","caller":"traceutil/trace.go:171","msg":"trace[278771575] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"103.028094ms","start":"2026-09-10T01:53:29.576732Z","end":"2026-09-10T01:53:29.679761Z","steps":["trace[278771575] 'process raft request' (duration: 89.32969ms)","trace[278771575] 'compare' (duration: 13.204257ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:29.978915Z","caller":"traceutil/trace.go:171","msg":"trace[1239227204] linearizableReadLoop","detail":"{readStateIndex:1844; appliedIndex:1841; }","duration":"145.243571ms","start":"2026-09-10T01:53:29.83365Z","end":"2026-09-10T01:53:29.978893Z","steps":["trace[1239227204] 'read index received' (duration: 37.21036ms)","trace[1239227204] 'applied index is now lower than readState.Index' (duration: 108.032321ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:29.979033Z","caller":"traceutil/trace.go:171","msg":"trace[1512816157] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"178.805652ms","start":"2026-09-10T01:53:29.800217Z","end":"2026-09-10T01:53:29.979023Z","steps":["trace[1512816157] 'process raft request' (duration: 177.738058ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:29.9794Z","caller":"traceutil/trace.go:171","msg":"trace[100246849] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"177.893599ms","start":"2026-09-10T01:53:29.801472Z","end":"2026-09-10T01:53:29.979365Z","steps":["trace[100246849] 'process raft request' (duration: 177.318051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:29.979504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.840089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:210555"} {"level":"info","ts":"2026-09-10T01:53:29.979576Z","caller":"traceutil/trace.go:171","msg":"trace[315234157] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1772; }","duration":"145.957571ms","start":"2026-09-10T01:53:29.833607Z","end":"2026-09-10T01:53:29.979565Z","steps":["trace[315234157] 'agreement among raft nodes before linearized reading' (duration: 145.602076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:30.226021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.973373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:210555"} {"level":"info","ts":"2026-09-10T01:53:30.226109Z","caller":"traceutil/trace.go:171","msg":"trace[616325039] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1775; }","duration":"110.129114ms","start":"2026-09-10T01:53:30.11596Z","end":"2026-09-10T01:53:30.226089Z","steps":["trace[616325039] 'range keys from in-memory index tree' (duration: 109.714048ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:40.347222Z","caller":"traceutil/trace.go:171","msg":"trace[490439109] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"280.098155ms","start":"2026-09-10T01:53:40.067099Z","end":"2026-09-10T01:53:40.347197Z","steps":["trace[490439109] 'process raft request' (duration: 279.950873ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:40.354395Z","caller":"traceutil/trace.go:171","msg":"trace[1063372237] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"259.080183ms","start":"2026-09-10T01:53:40.095293Z","end":"2026-09-10T01:53:40.354374Z","steps":["trace[1063372237] 'process raft request' (duration: 258.91377ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:40.354661Z","caller":"traceutil/trace.go:171","msg":"trace[1105074962] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"190.719319ms","start":"2026-09-10T01:53:40.163912Z","end":"2026-09-10T01:53:40.354632Z","steps":["trace[1105074962] 'process raft request' (duration: 190.427525ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:40.527039Z","caller":"traceutil/trace.go:171","msg":"trace[873169140] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"129.684169ms","start":"2026-09-10T01:53:40.397321Z","end":"2026-09-10T01:53:40.527005Z","steps":["trace[873169140] 'process raft request' (duration: 59.151826ms)","trace[873169140] 'compare' (duration: 70.389841ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:40.751946Z","caller":"traceutil/trace.go:171","msg":"trace[674557849] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"152.002456ms","start":"2026-09-10T01:53:40.599917Z","end":"2026-09-10T01:53:40.75192Z","steps":["trace[674557849] 'process raft request' (duration: 127.832074ms)","trace[674557849] 'compare' (duration: 24.062941ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:52.682339Z","caller":"traceutil/trace.go:171","msg":"trace[1127579912] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"104.061201ms","start":"2026-09-10T01:53:52.578255Z","end":"2026-09-10T01:53:52.682317Z","steps":["trace[1127579912] 'read index received' (duration: 103.740687ms)","trace[1127579912] 'applied index is now lower than readState.Index' (duration: 319.614µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:52.682455Z","caller":"traceutil/trace.go:171","msg":"trace[1687667321] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"202.003754ms","start":"2026-09-10T01:53:52.48042Z","end":"2026-09-10T01:53:52.682423Z","steps":["trace[1687667321] 'process raft request' (duration: 201.65469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:52.68267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.386665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-10T01:53:52.682903Z","caller":"traceutil/trace.go:171","msg":"trace[1244622527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2013; }","duration":"104.636617ms","start":"2026-09-10T01:53:52.578249Z","end":"2026-09-10T01:53:52.682886Z","steps":["trace[1244622527] 'agreement among raft nodes before linearized reading' (duration: 104.262954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:53.433049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.052749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:53:53.433206Z","caller":"traceutil/trace.go:171","msg":"trace[416158777] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2094; }","duration":"158.966071ms","start":"2026-09-10T01:53:53.274219Z","end":"2026-09-10T01:53:53.433185Z","steps":["trace[416158777] 'read index received' (duration: 22.291538ms)","trace[416158777] 'applied index is now lower than readState.Index' (duration: 136.673043ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:53.433295Z","caller":"traceutil/trace.go:171","msg":"trace[756741289] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"167.756691ms","start":"2026-09-10T01:53:53.265479Z","end":"2026-09-10T01:53:53.433236Z","steps":["trace[756741289] 'process raft request' (duration: 54.417959ms)","trace[756741289] 'compare' (duration: 112.931288ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:53:53.433721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.479688ms","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-10T01:53:53.433793Z","caller":"traceutil/trace.go:171","msg":"trace[1350264916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2018; }","duration":"159.59549ms","start":"2026-09-10T01:53:53.27418Z","end":"2026-09-10T01:53:53.433775Z","steps":["trace[1350264916] 'agreement among raft nodes before linearized reading' (duration: 159.380257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:53:53.434234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.860783ms","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-10T01:53:53.434328Z","caller":"traceutil/trace.go:171","msg":"trace[1775695331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2018; }","duration":"151.974625ms","start":"2026-09-10T01:53:53.282337Z","end":"2026-09-10T01:53:53.434312Z","steps":["trace[1775695331] 'agreement among raft nodes before linearized reading' (duration: 151.475859ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:53.590944Z","caller":"traceutil/trace.go:171","msg":"trace[1328920455] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2097; }","duration":"125.004827ms","start":"2026-09-10T01:53:53.465807Z","end":"2026-09-10T01:53:53.590811Z","steps":["trace[1328920455] 'read index received' (duration: 45.861281ms)","trace[1328920455] 'applied index is now lower than readState.Index' (duration: 79.142326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:53:53.590941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.12161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:53:53.591009Z","caller":"traceutil/trace.go:171","msg":"trace[2103521322] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"151.243395ms","start":"2026-09-10T01:53:53.43973Z","end":"2026-09-10T01:53:53.590973Z","steps":["trace[2103521322] 'process raft request' (duration: 94.332345ms)","trace[2103521322] 'compare' (duration: 56.671807ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:53:53.591072Z","caller":"traceutil/trace.go:171","msg":"trace[2075907787] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2020; }","duration":"125.324811ms","start":"2026-09-10T01:53:53.465734Z","end":"2026-09-10T01:53:53.591059Z","steps":["trace[2075907787] 'agreement among raft nodes before linearized reading' (duration: 125.094258ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:53:56.611343Z","caller":"traceutil/trace.go:171","msg":"trace[1805972338] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"122.407743ms","start":"2026-09-10T01:53:56.488908Z","end":"2026-09-10T01:53:56.611316Z","steps":["trace[1805972338] 'process raft request' (duration: 121.796395ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:54:19.521041Z","caller":"traceutil/trace.go:171","msg":"trace[119119157] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"110.897174ms","start":"2026-09-10T01:54:19.410127Z","end":"2026-09-10T01:54:19.521024Z","steps":["trace[119119157] 'read index received' (duration: 110.700551ms)","trace[119119157] 'applied index is now lower than readState.Index' (duration: 196.023µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:54:19.521102Z","caller":"traceutil/trace.go:171","msg":"trace[434756041] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"201.741391ms","start":"2026-09-10T01:54:19.319336Z","end":"2026-09-10T01:54:19.521078Z","steps":["trace[434756041] 'process raft request' (duration: 201.568149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:54:19.521375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.229659ms","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-10T01:54:19.521423Z","caller":"traceutil/trace.go:171","msg":"trace[652954479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2171; }","duration":"111.327829ms","start":"2026-09-10T01:54:19.410081Z","end":"2026-09-10T01:54:19.521409Z","steps":["trace[652954479] 'agreement among raft nodes before linearized reading' (duration: 111.015636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:54:19.791024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.953396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:54:19.791218Z","caller":"traceutil/trace.go:171","msg":"trace[1524149581] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"236.261777ms","start":"2026-09-10T01:54:19.554927Z","end":"2026-09-10T01:54:19.791189Z","steps":["trace[1524149581] 'read index received' (duration: 94.643152ms)","trace[1524149581] 'applied index is now lower than readState.Index' (duration: 141.616135ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:54:19.79126Z","caller":"traceutil/trace.go:171","msg":"trace[1032625242] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"266.786736ms","start":"2026-09-10T01:54:19.524433Z","end":"2026-09-10T01:54:19.79122Z","steps":["trace[1032625242] 'process raft request' (duration: 125.281483ms)","trace[1032625242] 'compare' (duration: 140.630063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:54:19.791564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.643212ms","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-10T01:54:19.791621Z","caller":"traceutil/trace.go:171","msg":"trace[315248458] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2172; }","duration":"236.749012ms","start":"2026-09-10T01:54:19.55485Z","end":"2026-09-10T01:54:19.7916Z","steps":["trace[315248458] 'agreement among raft nodes before linearized reading' (duration: 236.51332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:54:19.792074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.405718ms","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-10T01:54:19.792513Z","caller":"traceutil/trace.go:171","msg":"trace[1309888990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2172; }","duration":"167.895984ms","start":"2026-09-10T01:54:19.624603Z","end":"2026-09-10T01:54:19.792499Z","steps":["trace[1309888990] 'agreement among raft nodes before linearized reading' (duration: 167.395278ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:17.315597Z","caller":"traceutil/trace.go:171","msg":"trace[1370407939] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"172.933819ms","start":"2026-09-10T01:55:17.142641Z","end":"2026-09-10T01:55:17.315575Z","steps":["trace[1370407939] 'read index received' (duration: 172.68094ms)","trace[1370407939] 'applied index is now lower than readState.Index' (duration: 251.799µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:55:17.315905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.24522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-10T01:55:17.315987Z","caller":"traceutil/trace.go:171","msg":"trace[2146082297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2587; }","duration":"173.344623ms","start":"2026-09-10T01:55:17.142625Z","end":"2026-09-10T01:55:17.31597Z","steps":["trace[2146082297] 'agreement among raft nodes before linearized reading' (duration: 173.066823ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:17.316493Z","caller":"traceutil/trace.go:171","msg":"trace[477216204] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"205.325346ms","start":"2026-09-10T01:55:17.111146Z","end":"2026-09-10T01:55:17.316472Z","steps":["trace[477216204] 'process raft request' (duration: 204.253219ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:48.029369Z","caller":"traceutil/trace.go:171","msg":"trace[530412614] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"168.43499ms","start":"2026-09-10T01:55:47.860894Z","end":"2026-09-10T01:55:48.029329Z","steps":["trace[530412614] 'process raft request' (duration: 168.203893ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:53.702954Z","caller":"traceutil/trace.go:171","msg":"trace[1963397954] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"167.77641ms","start":"2026-09-10T01:55:53.535124Z","end":"2026-09-10T01:55:53.7029Z","steps":["trace[1963397954] 'process raft request' (duration: 167.645996ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:53.714352Z","caller":"traceutil/trace.go:171","msg":"trace[1344988331] linearizableReadLoop","detail":"{readStateIndex:2894; appliedIndex:2893; }","duration":"108.63253ms","start":"2026-09-10T01:55:53.605677Z","end":"2026-09-10T01:55:53.71431Z","steps":["trace[1344988331] 'read index received' (duration: 97.243557ms)","trace[1344988331] 'applied index is now lower than readState.Index' (duration: 11.387203ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:55:53.714619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.903298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T01:55:53.714663Z","caller":"traceutil/trace.go:171","msg":"trace[75866296] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2788; }","duration":"109.023311ms","start":"2026-09-10T01:55:53.605629Z","end":"2026-09-10T01:55:53.714652Z","steps":["trace[75866296] 'agreement among raft nodes before linearized reading' (duration: 108.822025ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:53.714824Z","caller":"traceutil/trace.go:171","msg":"trace[1271643293] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"178.907536ms","start":"2026-09-10T01:55:53.535893Z","end":"2026-09-10T01:55:53.7148Z","steps":["trace[1271643293] 'process raft request' (duration: 178.218395ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:25.223607Z","caller":"traceutil/trace.go:171","msg":"trace[899440451] linearizableReadLoop","detail":"{readStateIndex:5069; appliedIndex:5067; }","duration":"106.192485ms","start":"2026-09-10T02:00:25.117394Z","end":"2026-09-10T02:00:25.223587Z","steps":["trace[899440451] 'read index received' (duration: 105.925691ms)","trace[899440451] 'applied index is now lower than readState.Index' (duration: 266.044µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:25.223639Z","caller":"traceutil/trace.go:171","msg":"trace[891641917] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"147.46252ms","start":"2026-09-10T02:00:25.076146Z","end":"2026-09-10T02:00:25.223608Z","steps":["trace[891641917] 'process raft request' (duration: 147.271538ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:25.223713Z","caller":"traceutil/trace.go:171","msg":"trace[832283956] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"125.179492ms","start":"2026-09-10T02:00:25.098511Z","end":"2026-09-10T02:00:25.223691Z","steps":["trace[832283956] 'process raft request' (duration: 125.03654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:25.223758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.344757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T02:00:25.223792Z","caller":"traceutil/trace.go:171","msg":"trace[429640452] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:4881; }","duration":"106.425818ms","start":"2026-09-10T02:00:25.117357Z","end":"2026-09-10T02:00:25.223783Z","steps":["trace[429640452] 'agreement among raft nodes before linearized reading' (duration: 106.339247ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:31.508125Z","caller":"traceutil/trace.go:171","msg":"trace[993929261] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"237.694154ms","start":"2026-09-10T02:00:31.270403Z","end":"2026-09-10T02:00:31.508097Z","steps":["trace[993929261] 'process raft request' (duration: 163.350255ms)","trace[993929261] 'compare' (duration: 73.853601ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:31.6584Z","caller":"traceutil/trace.go:171","msg":"trace[716816053] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"387.55996ms","start":"2026-09-10T02:00:31.270803Z","end":"2026-09-10T02:00:31.658363Z","steps":["trace[716816053] 'process raft request' (duration: 338.861301ms)","trace[716816053] 'compare' (duration: 48.421575ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:00:31.658612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:00:31.270783Z","time spent":"387.718403ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:00:31.658684Z","caller":"traceutil/trace.go:171","msg":"trace[549515610] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"142.224726ms","start":"2026-09-10T02:00:31.516425Z","end":"2026-09-10T02:00:31.65865Z","steps":["trace[549515610] 'process raft request' (duration: 141.897031ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:32.031671Z","caller":"traceutil/trace.go:171","msg":"trace[1253827673] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"178.862917ms","start":"2026-09-10T02:00:31.852778Z","end":"2026-09-10T02:00:32.031641Z","steps":["trace[1253827673] 'process raft request' (duration: 178.698154ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:00:39.313724Z","caller":"traceutil/trace.go:171","msg":"trace[1556554880] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5314; }","duration":"102.999123ms","start":"2026-09-10T02:00:39.210688Z","end":"2026-09-10T02:00:39.313687Z","steps":["trace[1556554880] 'read index received' (duration: 102.660169ms)","trace[1556554880] 'applied index is now lower than readState.Index' (duration: 338.154µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:00:39.313828Z","caller":"traceutil/trace.go:171","msg":"trace[1043383654] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"116.659975ms","start":"2026-09-10T02:00:39.197117Z","end":"2026-09-10T02:00:39.313777Z","steps":["trace[1043383654] 'process raft request' (duration: 116.368281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:00:39.314151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.284497ms","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-09-10T02:00:39.314388Z","caller":"traceutil/trace.go:171","msg":"trace[2136165427] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5120; }","duration":"103.741135ms","start":"2026-09-10T02:00:39.210618Z","end":"2026-09-10T02:00:39.314359Z","steps":["trace[2136165427] 'agreement among raft nodes before linearized reading' (duration: 103.173706ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:01:12.191926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-09-10T02:01:12.266056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"72.902279ms","hash":2785912330,"current-db-size-bytes":51527680,"current-db-size":"52 MB","current-db-size-in-use-bytes":27783168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T02:01:12.266127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2785912330,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-09-10T02:01:27.713239Z","caller":"traceutil/trace.go:171","msg":"trace[786864087] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"106.133325ms","start":"2026-09-10T02:01:27.607079Z","end":"2026-09-10T02:01:27.713213Z","steps":["trace[786864087] 'process raft request' (duration: 90.247038ms)","trace[786864087] 'compare' (duration: 15.791505ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:01:28.295309Z","caller":"traceutil/trace.go:171","msg":"trace[658380239] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"120.69305ms","start":"2026-09-10T02:01:28.174582Z","end":"2026-09-10T02:01:28.295275Z","steps":["trace[658380239] 'process raft request' (duration: 120.517598ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:53.08875Z","caller":"traceutil/trace.go:171","msg":"trace[419756850] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"117.720209ms","start":"2026-09-10T02:04:52.970995Z","end":"2026-09-10T02:04:53.088715Z","steps":["trace[419756850] 'process raft request' (duration: 117.514812ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:06:12.20061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5371} {"level":"info","ts":"2026-09-10T02:06:12.280372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5371,"took":"78.147238ms","hash":2686700228,"current-db-size-bytes":51527680,"current-db-size":"52 MB","current-db-size-in-use-bytes":18812928,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T02:06:12.280429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2686700228,"revision":5371,"compact-revision":2887}