{"level":"warn","ts":"2026-03-01T21:07:55.096729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T21:07:55.096832Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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://199.204.45.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-03-01T21:07:55.096916Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T21:07:55.096938Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-03-01T21:07:55.096964Z","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-03-01T21:07:55.097943Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-03-01T21:07:55.098073Z","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://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-03-01T21:07:55.102001Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.618013ms"} {"level":"info","ts":"2026-03-01T21:07:55.109693Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-03-01T21:07:55.109797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T21:07:55.109832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-03-01T21:07:55.109849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T21:07:55.109894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-03-01T21:07:55.110036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-03-01T21:07:55.118553Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T21:07:55.120298Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T21:07:55.122586Z","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-03-01T21:07:55.125989Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T21:07:55.127387Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T21:07:55.127683Z","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-03-01T21:07:55.127732Z","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-03-01T21:07:55.127744Z","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-03-01T21:07:55.128247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-03-01T21:07:55.129366Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-03-01T21:07:55.132466Z","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-03-01T21:07:55.132598Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-03-01T21:07:55.132627Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-03-01T21:07:55.133558Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T21:07:55.13361Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T21:07:55.910313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T21:07:55.910463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T21:07:55.910583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-03-01T21:07:55.910645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-03-01T21:07:55.910687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-03-01T21:07:55.910729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-03-01T21:07:55.910776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-03-01T21:07:55.912181Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:07:55.913373Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T21:07:55.913418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T21:07:55.913786Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T21:07:55.914187Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:07:55.91442Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:07:55.914542Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:07:55.916832Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-03-01T21:07:55.917122Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T21:07:55.917157Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T21:07:55.917211Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T21:08:02.016373Z","caller":"traceutil/trace.go:171","msg":"trace[573334505] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"132.532993ms","start":"2026-03-01T21:08:01.883815Z","end":"2026-03-01T21:08:02.016348Z","steps":["trace[573334505] 'process raft request' (duration: 132.455713ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:02.018625Z","caller":"traceutil/trace.go:171","msg":"trace[1735348006] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"134.790408ms","start":"2026-03-01T21:08:01.883822Z","end":"2026-03-01T21:08:02.018612Z","steps":["trace[1735348006] 'process raft request' (duration: 134.681557ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:02.01863Z","caller":"traceutil/trace.go:171","msg":"trace[973398343] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:280; }","duration":"134.555836ms","start":"2026-03-01T21:08:01.884056Z","end":"2026-03-01T21:08:02.018612Z","steps":["trace[973398343] 'read index received' (duration: 132.293532ms)","trace[973398343] 'applied index is now lower than readState.Index' (duration: 2.261334ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:02.018662Z","caller":"traceutil/trace.go:171","msg":"trace[171707579] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"134.765207ms","start":"2026-03-01T21:08:01.883876Z","end":"2026-03-01T21:08:02.018641Z","steps":["trace[171707579] 'process raft request' (duration: 134.693847ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:02.0187Z","caller":"traceutil/trace.go:171","msg":"trace[1991675498] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"134.632826ms","start":"2026-03-01T21:08:01.884049Z","end":"2026-03-01T21:08:02.018682Z","steps":["trace[1991675498] 'process raft request' (duration: 134.540256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:02.018805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.2914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-01T21:08:02.018813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.731687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-03-01T21:08:02.018871Z","caller":"traceutil/trace.go:171","msg":"trace[1538101454] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:270; }","duration":"127.44025ms","start":"2026-03-01T21:08:01.891419Z","end":"2026-03-01T21:08:02.018859Z","steps":["trace[1538101454] 'agreement among raft nodes before linearized reading' (duration: 127.291139ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:02.018911Z","caller":"traceutil/trace.go:171","msg":"trace[1867691610] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:270; }","duration":"134.848807ms","start":"2026-03-01T21:08:01.884035Z","end":"2026-03-01T21:08:02.018884Z","steps":["trace[1867691610] 'agreement among raft nodes before linearized reading' (duration: 134.673156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:04.350452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.884137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-01T21:08:04.350555Z","caller":"traceutil/trace.go:171","msg":"trace[1049167699] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:294; }","duration":"108.038895ms","start":"2026-03-01T21:08:04.242498Z","end":"2026-03-01T21:08:04.350537Z","steps":["trace[1049167699] 'range keys from in-memory index tree' (duration: 107.787432ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:04.487079Z","caller":"traceutil/trace.go:171","msg":"trace[521546114] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"124.697577ms","start":"2026-03-01T21:08:04.362354Z","end":"2026-03-01T21:08:04.487051Z","steps":["trace[521546114] 'read index received' (duration: 124.474637ms)","trace[521546114] 'applied index is now lower than readState.Index' (duration: 221.62µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:04.487157Z","caller":"traceutil/trace.go:171","msg":"trace[1831894311] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"131.763169ms","start":"2026-03-01T21:08:04.355358Z","end":"2026-03-01T21:08:04.487121Z","steps":["trace[1831894311] 'process raft request' (duration: 131.511687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:04.487305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.929669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-01T21:08:04.487479Z","caller":"traceutil/trace.go:171","msg":"trace[226487684] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"125.15288ms","start":"2026-03-01T21:08:04.362309Z","end":"2026-03-01T21:08:04.487462Z","steps":["trace[226487684] 'agreement among raft nodes before linearized reading' (duration: 124.910599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:10.411571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695124604741976,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T21:08:10.616688Z","caller":"traceutil/trace.go:171","msg":"trace[1645145755] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"705.85394ms","start":"2026-03-01T21:08:09.910808Z","end":"2026-03-01T21:08:10.616662Z","steps":["trace[1645145755] 'read index received' (duration: 705.62052ms)","trace[1645145755] 'applied index is now lower than readState.Index' (duration: 232.68µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:10.616792Z","caller":"traceutil/trace.go:171","msg":"trace[782700139] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"857.141648ms","start":"2026-03-01T21:08:09.7596Z","end":"2026-03-01T21:08:10.616742Z","steps":["trace[782700139] 'process raft request' (duration: 856.909877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:10.616889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.062051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-01T21:08:10.616937Z","caller":"traceutil/trace.go:171","msg":"trace[302468359] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:367; }","duration":"706.133974ms","start":"2026-03-01T21:08:09.910789Z","end":"2026-03-01T21:08:10.616922Z","steps":["trace[302468359] 'agreement among raft nodes before linearized reading' (duration: 706.022719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:10.616989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:09.910693Z","time spent":"706.285361ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-01T21:08:10.617202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.65199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:08:10.617273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.115739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-01T21:08:10.617344Z","caller":"traceutil/trace.go:171","msg":"trace[348223259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"341.849419ms","start":"2026-03-01T21:08:10.275467Z","end":"2026-03-01T21:08:10.617317Z","steps":["trace[348223259] 'agreement among raft nodes before linearized reading' (duration: 341.627519ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:10.617433Z","caller":"traceutil/trace.go:171","msg":"trace[1811552451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"108.329609ms","start":"2026-03-01T21:08:10.509077Z","end":"2026-03-01T21:08:10.617406Z","steps":["trace[1811552451] 'agreement among raft nodes before linearized reading' (duration: 108.022995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:10.617479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:10.275445Z","time spent":"342.016126ms","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T21:08:10.617727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:09.759582Z","time spent":"857.327076ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:08:11.237227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.500886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:08:11.237346Z","caller":"traceutil/trace.go:171","msg":"trace[402548864] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"615.187699ms","start":"2026-03-01T21:08:10.622141Z","end":"2026-03-01T21:08:11.237329Z","steps":["trace[402548864] 'process raft request' (duration: 106.37191ms)","trace[402548864] 'compare' (duration: 508.333368ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:08:11.23741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:10.622117Z","time spent":"615.265233ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:11.23823Z","caller":"traceutil/trace.go:171","msg":"trace[1442071885] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"615.176307ms","start":"2026-03-01T21:08:10.623015Z","end":"2026-03-01T21:08:11.238192Z","steps":["trace[1442071885] 'process raft request' (duration: 614.944377ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:11.238314Z","caller":"traceutil/trace.go:171","msg":"trace[2447515] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"613.136966ms","start":"2026-03-01T21:08:10.625162Z","end":"2026-03-01T21:08:11.238298Z","steps":["trace[2447515] 'read index received' (duration: 103.316762ms)","trace[2447515] 'applied index is now lower than readState.Index' (duration: 509.819344ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:08:11.238417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.249011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:08:11.238454Z","caller":"traceutil/trace.go:171","msg":"trace[1168086545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"613.430799ms","start":"2026-03-01T21:08:10.625011Z","end":"2026-03-01T21:08:11.238442Z","steps":["trace[1168086545] 'agreement among raft nodes before linearized reading' (duration: 613.346605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:11.238489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:10.624996Z","time spent":"613.487122ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T21:08:11.238497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:10.623007Z","time spent":"615.352145ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:11.23875Z","caller":"traceutil/trace.go:171","msg":"trace[993346213] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"613.471431ms","start":"2026-03-01T21:08:10.625267Z","end":"2026-03-01T21:08:11.238739Z","steps":["trace[993346213] 'process raft request' (duration: 612.844813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:11.238824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:10.625254Z","time spent":"613.530694ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:11.285546Z","caller":"traceutil/trace.go:171","msg":"trace[1172094778] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"168.989716ms","start":"2026-03-01T21:08:11.116531Z","end":"2026-03-01T21:08:11.285521Z","steps":["trace[1172094778] 'process raft request' (duration: 168.660141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:11.841505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695124604741985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T21:08:12.24515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.084366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:08:12.245315Z","caller":"traceutil/trace.go:171","msg":"trace[658448682] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"904.133246ms","start":"2026-03-01T21:08:11.341155Z","end":"2026-03-01T21:08:12.245288Z","steps":["trace[658448682] 'read index received' (duration: 783.773098ms)","trace[658448682] 'applied index is now lower than readState.Index' (duration: 120.357588ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:12.245324Z","caller":"traceutil/trace.go:171","msg":"trace[2053639301] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"950.941389ms","start":"2026-03-01T21:08:11.294348Z","end":"2026-03-01T21:08:12.245289Z","steps":["trace[2053639301] 'process raft request' (duration: 830.585491ms)","trace[2053639301] 'compare' (duration: 118.771899ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:08:12.245525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.368616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-01T21:08:12.245555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:11.294328Z","time spent":"951.083416ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:12.245575Z","caller":"traceutil/trace.go:171","msg":"trace[1987059944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"904.45467ms","start":"2026-03-01T21:08:11.341107Z","end":"2026-03-01T21:08:12.245561Z","steps":["trace[1987059944] 'agreement among raft nodes before linearized reading' (duration: 904.327425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:12.245625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:11.341089Z","time spent":"904.521202ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T21:08:12.24593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.608051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:08:12.245983Z","caller":"traceutil/trace.go:171","msg":"trace[936435441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"619.705075ms","start":"2026-03-01T21:08:11.626267Z","end":"2026-03-01T21:08:12.245972Z","steps":["trace[936435441] 'agreement among raft nodes before linearized reading' (duration: 619.629452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:12.246048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:11.626238Z","time spent":"619.802429ms","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-01T21:08:31.278049Z","caller":"traceutil/trace.go:171","msg":"trace[1547830387] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"107.422643ms","start":"2026-03-01T21:08:31.170594Z","end":"2026-03-01T21:08:31.278017Z","steps":["trace[1547830387] 'process raft request' (duration: 84.483558ms)","trace[1547830387] 'compare' (duration: 22.781169ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:34.19601Z","caller":"traceutil/trace.go:171","msg":"trace[942309245] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"205.88942ms","start":"2026-03-01T21:08:33.99009Z","end":"2026-03-01T21:08:34.195979Z","steps":["trace[942309245] 'process raft request' (duration: 205.747775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:34.757871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.720206ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-01T21:08:34.757975Z","caller":"traceutil/trace.go:171","msg":"trace[667019431] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"341.349138ms","start":"2026-03-01T21:08:34.416607Z","end":"2026-03-01T21:08:34.757956Z","steps":["trace[667019431] 'read index received' (duration: 27.261µs)","trace[667019431] 'applied index is now lower than readState.Index' (duration: 341.320387ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:08:34.758097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.382472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:08:34.758152Z","caller":"traceutil/trace.go:171","msg":"trace[1779321453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"133.483355ms","start":"2026-03-01T21:08:34.624654Z","end":"2026-03-01T21:08:34.758137Z","steps":["trace[1779321453] 'agreement among raft nodes before linearized reading' (duration: 133.401883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:34.758186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.548595ms","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-03-01T21:08:34.758224Z","caller":"traceutil/trace.go:171","msg":"trace[1742601597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"341.634658ms","start":"2026-03-01T21:08:34.416578Z","end":"2026-03-01T21:08:34.758213Z","steps":["trace[1742601597] 'agreement among raft nodes before linearized reading' (duration: 341.480523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:34.758257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:34.416561Z","time spent":"341.686159ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T21:08:36.126405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389695124604742273,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T21:08:36.283014Z","caller":"traceutil/trace.go:171","msg":"trace[154853668] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"703.733627ms","start":"2026-03-01T21:08:35.579262Z","end":"2026-03-01T21:08:36.282996Z","steps":["trace[154853668] 'process raft request' (duration: 703.608103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:36.283146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:35.579232Z","time spent":"703.843671ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:36.405079Z","caller":"traceutil/trace.go:171","msg":"trace[1784684800] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"779.769288ms","start":"2026-03-01T21:08:35.625292Z","end":"2026-03-01T21:08:36.405062Z","steps":["trace[1784684800] 'read index received' (duration: 657.627268ms)","trace[1784684800] 'applied index is now lower than readState.Index' (duration: 122.14099ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:08:36.405223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.922763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:08:36.405246Z","caller":"traceutil/trace.go:171","msg":"trace[2099888106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"780.018876ms","start":"2026-03-01T21:08:35.625222Z","end":"2026-03-01T21:08:36.405241Z","steps":["trace[2099888106] 'agreement among raft nodes before linearized reading' (duration: 779.908032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:36.405268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:35.625198Z","time spent":"780.066268ms","remote":"127.0.0.1:42148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-01T21:08:36.405532Z","caller":"traceutil/trace.go:171","msg":"trace[580992335] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"825.08642ms","start":"2026-03-01T21:08:35.580439Z","end":"2026-03-01T21:08:36.405525Z","steps":["trace[580992335] 'process raft request' (duration: 824.492871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:36.405599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:35.580405Z","time spent":"825.150812ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:36.945426Z","caller":"traceutil/trace.go:171","msg":"trace[1491807255] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"140.777929ms","start":"2026-03-01T21:08:36.804628Z","end":"2026-03-01T21:08:36.945406Z","steps":["trace[1491807255] 'read index received' (duration: 140.645694ms)","trace[1491807255] 'applied index is now lower than readState.Index' (duration: 131.395µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:36.945485Z","caller":"traceutil/trace.go:171","msg":"trace[1252024414] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"164.887811ms","start":"2026-03-01T21:08:36.780573Z","end":"2026-03-01T21:08:36.945461Z","steps":["trace[1252024414] 'process raft request' (duration: 164.705125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:36.94558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.936725ms","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-03-01T21:08:36.945607Z","caller":"traceutil/trace.go:171","msg":"trace[969422888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"141.011557ms","start":"2026-03-01T21:08:36.804586Z","end":"2026-03-01T21:08:36.945598Z","steps":["trace[969422888] 'agreement among raft nodes before linearized reading' (duration: 140.899453ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:38.839709Z","caller":"traceutil/trace.go:171","msg":"trace[662214739] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"485.43478ms","start":"2026-03-01T21:08:38.35424Z","end":"2026-03-01T21:08:38.839675Z","steps":["trace[662214739] 'read index received' (duration: 485.12565ms)","trace[662214739] 'applied index is now lower than readState.Index' (duration: 307.1µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:38.839837Z","caller":"traceutil/trace.go:171","msg":"trace[1156369456] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"512.059287ms","start":"2026-03-01T21:08:38.327735Z","end":"2026-03-01T21:08:38.839794Z","steps":["trace[1156369456] 'process raft request' (duration: 511.699816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:38.839993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:38.32771Z","time spent":"512.224593ms","remote":"127.0.0.1:42210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T21:08:38.840105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.816383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-01T21:08:38.840154Z","caller":"traceutil/trace.go:171","msg":"trace[647252924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"485.938186ms","start":"2026-03-01T21:08:38.354205Z","end":"2026-03-01T21:08:38.840143Z","steps":["trace[647252924] 'agreement among raft nodes before linearized reading' (duration: 485.656247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:38.840291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:38.354189Z","time spent":"485.984408ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T21:08:38.845451Z","caller":"traceutil/trace.go:171","msg":"trace[2076477665] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"382.340287ms","start":"2026-03-01T21:08:38.463095Z","end":"2026-03-01T21:08:38.845435Z","steps":["trace[2076477665] 'process raft request' (duration: 382.13621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:38.845535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:38.46307Z","time spent":"382.42ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:08:38.845531Z","caller":"traceutil/trace.go:171","msg":"trace[513173089] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"118.228296ms","start":"2026-03-01T21:08:38.727261Z","end":"2026-03-01T21:08:38.845489Z","steps":["trace[513173089] 'process raft request' (duration: 118.082681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:38.845677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.976391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:08:38.845788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.676522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:08:38.845812Z","caller":"traceutil/trace.go:171","msg":"trace[1865640303] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"321.160917ms","start":"2026-03-01T21:08:38.5246Z","end":"2026-03-01T21:08:38.845761Z","steps":["trace[1865640303] 'agreement among raft nodes before linearized reading' (duration: 320.980612ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:38.845824Z","caller":"traceutil/trace.go:171","msg":"trace[557927158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"435.744934ms","start":"2026-03-01T21:08:38.410069Z","end":"2026-03-01T21:08:38.845814Z","steps":["trace[557927158] 'agreement among raft nodes before linearized reading' (duration: 435.414243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:38.845853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:38.410052Z","time spent":"435.795686ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T21:08:38.845859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:08:38.524569Z","time spent":"321.278611ms","remote":"127.0.0.1:42210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-03-01T21:08:45.03205Z","caller":"traceutil/trace.go:171","msg":"trace[1489626488] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"158.588191ms","start":"2026-03-01T21:08:44.873438Z","end":"2026-03-01T21:08:45.032026Z","steps":["trace[1489626488] 'read index received' (duration: 158.394565ms)","trace[1489626488] 'applied index is now lower than readState.Index' (duration: 192.716µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:45.032103Z","caller":"traceutil/trace.go:171","msg":"trace[1592083916] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"158.979634ms","start":"2026-03-01T21:08:44.873104Z","end":"2026-03-01T21:08:45.032083Z","steps":["trace[1592083916] 'process raft request' (duration: 158.779917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:45.032264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.806259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:08:45.032365Z","caller":"traceutil/trace.go:171","msg":"trace[1313714184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"158.951063ms","start":"2026-03-01T21:08:44.873404Z","end":"2026-03-01T21:08:45.032355Z","steps":["trace[1313714184] 'agreement among raft nodes before linearized reading' (duration: 158.750117ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:08:55.355293Z","caller":"traceutil/trace.go:171","msg":"trace[96267601] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"192.061757ms","start":"2026-03-01T21:08:55.163194Z","end":"2026-03-01T21:08:55.355256Z","steps":["trace[96267601] 'read index received' (duration: 191.84682ms)","trace[96267601] 'applied index is now lower than readState.Index' (duration: 213.846µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:08:55.355403Z","caller":"traceutil/trace.go:171","msg":"trace[1311116365] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"192.988071ms","start":"2026-03-01T21:08:55.162389Z","end":"2026-03-01T21:08:55.355377Z","steps":["trace[1311116365] 'process raft request' (duration: 192.721643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:08:55.355585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.374945ms","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-03-01T21:08:55.355678Z","caller":"traceutil/trace.go:171","msg":"trace[1941010099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"192.507598ms","start":"2026-03-01T21:08:55.163152Z","end":"2026-03-01T21:08:55.35566Z","steps":["trace[1941010099] 'agreement among raft nodes before linearized reading' (duration: 192.240921ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:09:45.547726Z","caller":"traceutil/trace.go:171","msg":"trace[1038200909] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"160.770172ms","start":"2026-03-01T21:09:45.386926Z","end":"2026-03-01T21:09:45.547696Z","steps":["trace[1038200909] 'process raft request' (duration: 94.669929ms)","trace[1038200909] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 65.959881ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:09:51.533333Z","caller":"traceutil/trace.go:171","msg":"trace[1088920330] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"123.728777ms","start":"2026-03-01T21:09:51.409488Z","end":"2026-03-01T21:09:51.533216Z","steps":["trace[1088920330] 'process raft request' (duration: 123.200138ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:10:02.720495Z","caller":"traceutil/trace.go:171","msg":"trace[1357460842] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"193.179267ms","start":"2026-03-01T21:10:02.527266Z","end":"2026-03-01T21:10:02.720445Z","steps":["trace[1357460842] 'read index received' (duration: 192.830882ms)","trace[1357460842] 'applied index is now lower than readState.Index' (duration: 347.285µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:10:02.720629Z","caller":"traceutil/trace.go:171","msg":"trace[1331777418] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"253.953935ms","start":"2026-03-01T21:10:02.466644Z","end":"2026-03-01T21:10:02.720598Z","steps":["trace[1331777418] 'process raft request' (duration: 253.590249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:10:02.720811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.518143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:10:02.721152Z","caller":"traceutil/trace.go:171","msg":"trace[916699474] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:943; }","duration":"193.909189ms","start":"2026-03-01T21:10:02.527228Z","end":"2026-03-01T21:10:02.721137Z","steps":["trace[916699474] 'agreement among raft nodes before linearized reading' (duration: 193.438982ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:10:14.907779Z","caller":"traceutil/trace.go:171","msg":"trace[353515459] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"123.55769ms","start":"2026-03-01T21:10:14.784195Z","end":"2026-03-01T21:10:14.907752Z","steps":["trace[353515459] 'read index received' (duration: 123.231855ms)","trace[353515459] 'applied index is now lower than readState.Index' (duration: 324.615µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:10:14.907833Z","caller":"traceutil/trace.go:171","msg":"trace[1336772923] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"124.909519ms","start":"2026-03-01T21:10:14.782893Z","end":"2026-03-01T21:10:14.907802Z","steps":["trace[1336772923] 'process raft request' (duration: 124.675906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:10:14.907987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.761243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-03-01T21:10:14.908037Z","caller":"traceutil/trace.go:171","msg":"trace[1296253146] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:988; }","duration":"123.842804ms","start":"2026-03-01T21:10:14.78418Z","end":"2026-03-01T21:10:14.908023Z","steps":["trace[1296253146] 'agreement among raft nodes before linearized reading' (duration: 123.728712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:10:14.908014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.921304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-03-01T21:10:14.908102Z","caller":"traceutil/trace.go:171","msg":"trace[1279778996] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:988; }","duration":"118.027986ms","start":"2026-03-01T21:10:14.790053Z","end":"2026-03-01T21:10:14.908081Z","steps":["trace[1279778996] 'agreement among raft nodes before linearized reading' (duration: 117.878673ms)"],"step_count":1}